builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-039 starttime: 1456983909.14 results: success (0) buildid: 20160302191720 builduid: 4765af16026e4fd2ab08ee02376d0ce8 revision: 7b0d105d7da5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.138997) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.139446) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.139913) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005271 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.186769) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.187088) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.224078) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.224343) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.025943 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.270407) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.270681) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.271015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.271304) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-02 21:45:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-03-02 21:45:09 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.236492 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.520040) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.520317) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.197218 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.728328) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:09.728634) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7b0d105d7da5 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7b0d105d7da5 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-02 21:45:09,786 Setting DEBUG logging. 2016-03-02 21:45:09,787 attempt 1/10 2016-03-02 21:45:09,787 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7b0d105d7da5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-02 21:45:10,211 unpacking tar archive at: mozilla-beta-7b0d105d7da5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.692541 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:10.432662) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:10.433026) ========= echo 7b0d105d7da5 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'7b0d105d7da5'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 7b0d105d7da5 program finished with exit code 0 elapsedTime=0.003450 script_repo_revision: '7b0d105d7da5' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:10.480237) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:10.480508) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 21:45:10.490294) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-03-02 21:45:10.490571) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:45:10 INFO - MultiFileLogger online at 20160302 21:45:10 in /builds/slave/test-pgo 21:45:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 21:45:10 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:45:10 INFO - {'append_to_log': False, 21:45:10 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:45:10 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:45:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:45:10 INFO - 'branch': 'Mozilla-Beta', 21:45:10 INFO - 'buildbot_json_path': 'buildprops.json', 21:45:10 INFO - 'config_files': ('talos/linux_config.py',), 21:45:10 INFO - 'default_actions': ('clobber', 21:45:10 INFO - 'read-buildbot-config', 21:45:10 INFO - 'download-and-extract', 21:45:10 INFO - 'populate-webroot', 21:45:10 INFO - 'create-virtualenv', 21:45:10 INFO - 'install', 21:45:10 INFO - 'run-tests'), 21:45:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:45:10 INFO - 'download_minidump_stackwalk': True, 21:45:10 INFO - 'download_symbols': 'ondemand', 21:45:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:45:10 INFO - 'tooltool.py': '/tools/tooltool.py', 21:45:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:45:10 INFO - '/tools/misc-python/virtualenv.py')}, 21:45:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:45:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:45:10 INFO - 'installer_path': 'installer.exe', 21:45:10 INFO - 'log_level': 'info', 21:45:10 INFO - 'log_name': 'talos', 21:45:10 INFO - 'log_to_console': True, 21:45:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:45:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:45:10 INFO - 'opt_config_files': (), 21:45:10 INFO - 'pip_index': False, 21:45:10 INFO - 'sps_profile': False, 21:45:10 INFO - 'sps_profile_interval': 0, 21:45:10 INFO - 'suite': 'dromaeojs', 21:45:10 INFO - 'system_bits': '32', 21:45:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:45:10 INFO - 'title': 'talos-linux64-ix-039', 21:45:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:45:10 INFO - 'use_talos_json': True, 21:45:10 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:45:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:45:10 INFO - 'work_dir': 'build'} 21:45:10 INFO - ##### 21:45:10 INFO - ##### Running clobber step. 21:45:10 INFO - ##### 21:45:10 INFO - Running pre-action listener: _resource_record_pre_action 21:45:10 INFO - Running main action method: clobber 21:45:10 INFO - rmtree: /builds/slave/test-pgo/build 21:45:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:45:11 INFO - Running post-action listener: _resource_record_post_action 21:45:11 INFO - ##### 21:45:11 INFO - ##### Running read-buildbot-config step. 21:45:11 INFO - ##### 21:45:11 INFO - Running pre-action listener: _resource_record_pre_action 21:45:11 INFO - Running main action method: read_buildbot_config 21:45:11 INFO - Using buildbot properties: 21:45:11 INFO - { 21:45:11 INFO - "properties": { 21:45:11 INFO - "buildnumber": 126, 21:45:11 INFO - "product": "firefox", 21:45:11 INFO - "script_repo_revision": "production", 21:45:11 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 21:45:11 INFO - "repository": "", 21:45:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 21:45:11 INFO - "buildid": "20160302191720", 21:45:11 INFO - "slavename": "talos-linux64-ix-039", 21:45:11 INFO - "pgo_build": "True", 21:45:11 INFO - "basedir": "/builds/slave/test-pgo", 21:45:11 INFO - "project": "", 21:45:11 INFO - "platform": "ubuntu64_hw", 21:45:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:45:11 INFO - "slavebuilddir": "test", 21:45:11 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 21:45:11 INFO - "branch": "mozilla-beta", 21:45:11 INFO - "repo_path": "releases/mozilla-beta", 21:45:11 INFO - "stage_platform": "linux64-pgo", 21:45:11 INFO - "builduid": "4765af16026e4fd2ab08ee02376d0ce8", 21:45:11 INFO - "revision": "7b0d105d7da5" 21:45:11 INFO - }, 21:45:11 INFO - "sourcestamp": { 21:45:11 INFO - "repository": "", 21:45:11 INFO - "hasPatch": false, 21:45:11 INFO - "project": "", 21:45:11 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 21:45:11 INFO - "changes": [ 21:45:11 INFO - { 21:45:11 INFO - "category": null, 21:45:11 INFO - "files": [ 21:45:11 INFO - { 21:45:11 INFO - "url": null, 21:45:11 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2" 21:45:11 INFO - } 21:45:11 INFO - ], 21:45:11 INFO - "repository": "", 21:45:11 INFO - "rev": "7b0d105d7da5", 21:45:11 INFO - "who": "sendchange", 21:45:11 INFO - "when": 1456983849, 21:45:11 INFO - "number": 7685901, 21:45:11 INFO - "comments": "Bug 1250311 - Update wpt annotations to reflect reality on GTK+2 branches after the Mesa upgrade, a=testonly", 21:45:11 INFO - "project": "", 21:45:11 INFO - "at": "Wed 02 Mar 2016 21:44:09", 21:45:11 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 21:45:11 INFO - "revlink": "", 21:45:11 INFO - "properties": [ 21:45:11 INFO - [ 21:45:11 INFO - "buildid", 21:45:11 INFO - "20160302191720", 21:45:11 INFO - "Change" 21:45:11 INFO - ], 21:45:11 INFO - [ 21:45:11 INFO - "builduid", 21:45:11 INFO - "4765af16026e4fd2ab08ee02376d0ce8", 21:45:11 INFO - "Change" 21:45:11 INFO - ], 21:45:11 INFO - [ 21:45:11 INFO - "pgo_build", 21:45:11 INFO - "True", 21:45:11 INFO - "Change" 21:45:11 INFO - ] 21:45:11 INFO - ], 21:45:11 INFO - "revision": "7b0d105d7da5" 21:45:11 INFO - } 21:45:11 INFO - ], 21:45:11 INFO - "revision": "7b0d105d7da5" 21:45:11 INFO - } 21:45:11 INFO - } 21:45:11 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2. 21:45:11 INFO - Running post-action listener: _resource_record_post_action 21:45:11 INFO - ##### 21:45:11 INFO - ##### Running download-and-extract step. 21:45:11 INFO - ##### 21:45:11 INFO - Running pre-action listener: _resource_record_pre_action 21:45:11 INFO - Running main action method: download_and_extract 21:45:11 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:11 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/test_packages.json 21:45:11 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:45:11 INFO - Downloaded 1270 bytes. 21:45:11 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:45:11 INFO - Using the following test package requirements: 21:45:11 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 21:45:11 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 21:45:11 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 21:45:11 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 21:45:11 INFO - u'jsshell-linux-x86_64.zip'], 21:45:11 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 21:45:11 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 21:45:11 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 21:45:11 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 21:45:11 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 21:45:11 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 21:45:11 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 21:45:11 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 21:45:11 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 21:45:11 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 21:45:11 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 21:45:11 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 21:45:11 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:11 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.common.tests.zip 21:45:11 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 21:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:45:12 INFO - Downloaded 21584590 bytes. 21:45:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:45:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 21:45:12 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:45:13 INFO - Return code: 0 21:45:13 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.common.tests.zip 21:45:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 21:45:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:45:14 INFO - Downloaded 21584590 bytes. 21:45:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:45:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 21:45:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:45:16 INFO - Return code: 0 21:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 21:45:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 21:45:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:45:18 INFO - Downloaded 11132038 bytes. 21:45:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 21:45:18 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 21:45:18 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 21:45:18 INFO - Return code: 0 21:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2 21:45:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 21:45:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:45:24 INFO - Downloaded 57945735 bytes. 21:45:24 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2 21:45:24 INFO - mkdir: /builds/slave/test-pgo/properties 21:45:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:45:24 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:45:24 INFO - Contents: 21:45:24 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2 21:45:24 INFO - Running post-action listener: _resource_record_post_action 21:45:24 INFO - Running post-action listener: set_extra_try_arguments 21:45:24 INFO - ##### 21:45:24 INFO - ##### Running populate-webroot step. 21:45:24 INFO - ##### 21:45:24 INFO - Running pre-action listener: _resource_record_pre_action 21:45:24 INFO - Running main action method: populate_webroot 21:45:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:45:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:45:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:45:24 INFO - '--tppagecycles', 21:45:24 INFO - '7'], 21:45:24 INFO - 'tests': ['tsvgm']}}, 21:45:24 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:45:24 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:45:24 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:45:24 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:45:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:45:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:45:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'tests': ['damp', 'tps']}, 21:45:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['damp', 'tps']}, 21:45:24 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:45:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:45:24 INFO - 'other': {'tests': ['a11yr', 21:45:24 INFO - 'ts_paint', 21:45:24 INFO - 'tpaint', 21:45:24 INFO - 'sessionrestore', 21:45:24 INFO - 'sessionrestore_no_auto_restore']}, 21:45:24 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['a11yr', 21:45:24 INFO - 'ts_paint', 21:45:24 INFO - 'tpaint', 21:45:24 INFO - 'sessionrestore', 21:45:24 INFO - 'sessionrestore_no_auto_restore']}, 21:45:24 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['a11yr', 21:45:24 INFO - 'ts_paint', 21:45:24 INFO - 'tpaint', 21:45:24 INFO - 'sessionrestore', 21:45:24 INFO - 'sessionrestore_no_auto_restore']}, 21:45:24 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['a11yr', 21:45:24 INFO - 'ts_paint', 21:45:24 INFO - 'tpaint', 21:45:24 INFO - 'sessionrestore', 21:45:24 INFO - 'sessionrestore_no_auto_restore']}, 21:45:24 INFO - 'other_l64': {'tests': ['a11yr', 21:45:24 INFO - 'ts_paint', 21:45:24 INFO - 'tpaint', 21:45:24 INFO - 'sessionrestore', 21:45:24 INFO - 'sessionrestore_no_auto_restore']}, 21:45:24 INFO - 'other_nol64': {'tests': ['a11yr', 21:45:24 INFO - 'ts_paint', 21:45:24 INFO - 'tpaint', 21:45:24 INFO - 'sessionrestore', 21:45:24 INFO - 'sessionrestore_no_auto_restore']}, 21:45:24 INFO - 'svgr': {'tests': ['tsvgx', 21:45:24 INFO - 'tsvgr_opacity', 21:45:24 INFO - 'tart', 21:45:24 INFO - 'tscrollx', 21:45:24 INFO - 'cart']}, 21:45:24 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['tsvgx', 21:45:24 INFO - 'tsvgr_opacity', 21:45:24 INFO - 'tart', 21:45:24 INFO - 'tscrollx', 21:45:24 INFO - 'cart']}, 21:45:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'tests': ['tp5o']}, 21:45:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'talos_options': ['--e10s'], 21:45:24 INFO - 'tests': ['tp5o']}, 21:45:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'talos_options': ['--xperf_path', 21:45:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:45:24 INFO - 'tests': ['tp5n']}, 21:45:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:45:24 INFO - 'talos_options': ['--e10s', 21:45:24 INFO - '--xperf_path', 21:45:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:45:24 INFO - 'tests': ['tp5n']}}, 21:45:24 INFO - 'talos.zip': {'path': '', 21:45:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:45:24 INFO - Running post-action listener: _resource_record_post_action 21:45:24 INFO - ##### 21:45:24 INFO - ##### Running create-virtualenv step. 21:45:24 INFO - ##### 21:45:24 INFO - Running pre-action listener: _resource_record_pre_action 21:45:24 INFO - Running main action method: create_virtualenv 21:45:24 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:45:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 21:45:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:45:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:45:24 INFO - Using real prefix '/usr' 21:45:24 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:45:25 INFO - Installing distribute.............................................................................................................................................................................................done. 21:45:28 INFO - Installing pip.................done. 21:45:28 INFO - Return code: 0 21:45:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 21:45:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:45:28 INFO - Using env: {'DISPLAY': ':0', 21:45:28 INFO - 'HOME': '/home/cltbld', 21:45:28 INFO - 'LANG': 'en_US.UTF-8', 21:45:28 INFO - 'LANGUAGE': 'en_US:en', 21:45:28 INFO - 'LOGNAME': 'cltbld', 21:45:28 INFO - 'MAIL': '/var/mail/cltbld', 21:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:28 INFO - 'MOZ_NO_REMOTE': '1', 21:45:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:28 INFO - 'NO_EM_RESTART': '1', 21:45:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:28 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:28 INFO - 'SHELL': '/bin/bash', 21:45:28 INFO - 'SHLVL': '1', 21:45:28 INFO - 'TERM': 'linux', 21:45:28 INFO - 'TMOUT': '86400', 21:45:28 INFO - 'USER': 'cltbld', 21:45:28 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:28 INFO - '_': '/tools/buildbot/bin/python'} 21:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:45:29 INFO - Cleaning up... 21:45:29 INFO - Return code: 0 21:45:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 21:45:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:45:29 INFO - Using env: {'DISPLAY': ':0', 21:45:29 INFO - 'HOME': '/home/cltbld', 21:45:29 INFO - 'LANG': 'en_US.UTF-8', 21:45:29 INFO - 'LANGUAGE': 'en_US:en', 21:45:29 INFO - 'LOGNAME': 'cltbld', 21:45:29 INFO - 'MAIL': '/var/mail/cltbld', 21:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:29 INFO - 'MOZ_NO_REMOTE': '1', 21:45:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:29 INFO - 'NO_EM_RESTART': '1', 21:45:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:29 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:29 INFO - 'SHELL': '/bin/bash', 21:45:29 INFO - 'SHLVL': '1', 21:45:29 INFO - 'TERM': 'linux', 21:45:29 INFO - 'TMOUT': '86400', 21:45:29 INFO - 'USER': 'cltbld', 21:45:29 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:29 INFO - '_': '/tools/buildbot/bin/python'} 21:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:29 INFO - Downloading/unpacking psutil>=0.7.1 21:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:45:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:45:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:45:31 INFO - Installing collected packages: psutil 21:45:31 INFO - Running setup.py install for psutil 21:45:31 INFO - building 'psutil._psutil_linux' extension 21:45:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:45:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:45:32 INFO - building 'psutil._psutil_posix' extension 21:45:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:45:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:45:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:45:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:45:32 INFO - Successfully installed psutil 21:45:32 INFO - Cleaning up... 21:45:32 INFO - Return code: 0 21:45:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 21:45:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:45:32 INFO - Using env: {'DISPLAY': ':0', 21:45:32 INFO - 'HOME': '/home/cltbld', 21:45:32 INFO - 'LANG': 'en_US.UTF-8', 21:45:32 INFO - 'LANGUAGE': 'en_US:en', 21:45:32 INFO - 'LOGNAME': 'cltbld', 21:45:32 INFO - 'MAIL': '/var/mail/cltbld', 21:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:32 INFO - 'MOZ_NO_REMOTE': '1', 21:45:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:32 INFO - 'NO_EM_RESTART': '1', 21:45:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:32 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:32 INFO - 'SHELL': '/bin/bash', 21:45:32 INFO - 'SHLVL': '1', 21:45:32 INFO - 'TERM': 'linux', 21:45:32 INFO - 'TMOUT': '86400', 21:45:32 INFO - 'USER': 'cltbld', 21:45:32 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:32 INFO - '_': '/tools/buildbot/bin/python'} 21:45:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:45:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:45:34 INFO - Installing collected packages: mozsystemmonitor 21:45:34 INFO - Running setup.py install for mozsystemmonitor 21:45:34 INFO - Successfully installed mozsystemmonitor 21:45:34 INFO - Cleaning up... 21:45:34 INFO - Return code: 0 21:45:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 21:45:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:45:34 INFO - Using env: {'DISPLAY': ':0', 21:45:34 INFO - 'HOME': '/home/cltbld', 21:45:34 INFO - 'LANG': 'en_US.UTF-8', 21:45:34 INFO - 'LANGUAGE': 'en_US:en', 21:45:34 INFO - 'LOGNAME': 'cltbld', 21:45:34 INFO - 'MAIL': '/var/mail/cltbld', 21:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:34 INFO - 'MOZ_NO_REMOTE': '1', 21:45:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:34 INFO - 'NO_EM_RESTART': '1', 21:45:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:34 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:34 INFO - 'SHELL': '/bin/bash', 21:45:34 INFO - 'SHLVL': '1', 21:45:34 INFO - 'TERM': 'linux', 21:45:34 INFO - 'TMOUT': '86400', 21:45:34 INFO - 'USER': 'cltbld', 21:45:34 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:34 INFO - '_': '/tools/buildbot/bin/python'} 21:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:35 INFO - Downloading/unpacking blobuploader==1.2.4 21:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:36 INFO - Downloading blobuploader-1.2.4.tar.gz 21:45:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:45:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:45:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:37 INFO - Downloading docopt-0.6.1.tar.gz 21:45:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:45:37 INFO - Installing collected packages: blobuploader, requests, docopt 21:45:37 INFO - Running setup.py install for blobuploader 21:45:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:45:37 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:45:37 INFO - Running setup.py install for requests 21:45:38 INFO - Running setup.py install for docopt 21:45:38 INFO - Successfully installed blobuploader requests docopt 21:45:38 INFO - Cleaning up... 21:45:38 INFO - Return code: 0 21:45:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 21:45:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:45:38 INFO - Using env: {'DISPLAY': ':0', 21:45:38 INFO - 'HOME': '/home/cltbld', 21:45:38 INFO - 'LANG': 'en_US.UTF-8', 21:45:38 INFO - 'LANGUAGE': 'en_US:en', 21:45:38 INFO - 'LOGNAME': 'cltbld', 21:45:38 INFO - 'MAIL': '/var/mail/cltbld', 21:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:38 INFO - 'MOZ_NO_REMOTE': '1', 21:45:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:38 INFO - 'NO_EM_RESTART': '1', 21:45:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:38 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:38 INFO - 'SHELL': '/bin/bash', 21:45:38 INFO - 'SHLVL': '1', 21:45:38 INFO - 'TERM': 'linux', 21:45:38 INFO - 'TMOUT': '86400', 21:45:38 INFO - 'USER': 'cltbld', 21:45:38 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:38 INFO - '_': '/tools/buildbot/bin/python'} 21:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:45:38 INFO - Running setup.py (path:/tmp/pip-JlMBdU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:45:38 INFO - Running setup.py (path:/tmp/pip-uQTtCv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:45:38 INFO - Running setup.py (path:/tmp/pip-k2YT0L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:45:38 INFO - Running setup.py (path:/tmp/pip-kcHr0G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:45:38 INFO - Running setup.py (path:/tmp/pip-bK2hbR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:45:38 INFO - Running setup.py (path:/tmp/pip-W3BOqQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:45:38 INFO - Running setup.py (path:/tmp/pip-GnzxGy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:45:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:45:38 INFO - Running setup.py (path:/tmp/pip-Y8bKW8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:45:39 INFO - Running setup.py (path:/tmp/pip-9nhPca-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:45:39 INFO - Running setup.py (path:/tmp/pip-RwawHE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:45:39 INFO - Running setup.py (path:/tmp/pip-XXQmTm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:45:39 INFO - Running setup.py (path:/tmp/pip-TnFGz0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:45:39 INFO - Running setup.py (path:/tmp/pip-UAC8Ik-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:45:39 INFO - Running setup.py (path:/tmp/pip-G1yAo5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:45:39 INFO - Running setup.py (path:/tmp/pip-RD8RS9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:45:39 INFO - Running setup.py (path:/tmp/pip-UB2lCd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:45:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:45:39 INFO - Running setup.py (path:/tmp/pip-UX4ldh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:45:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:45:39 INFO - Running setup.py install for manifestparser 21:45:39 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:45:39 INFO - Running setup.py install for mozcrash 21:45:39 INFO - Running setup.py install for mozdebug 21:45:39 INFO - Running setup.py install for mozdevice 21:45:40 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Running setup.py install for mozfile 21:45:40 INFO - Running setup.py install for mozhttpd 21:45:40 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Running setup.py install for mozinfo 21:45:40 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Running setup.py install for mozInstall 21:45:40 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Running setup.py install for mozleak 21:45:40 INFO - Running setup.py install for mozlog 21:45:40 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Running setup.py install for moznetwork 21:45:40 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Running setup.py install for mozprocess 21:45:40 INFO - Running setup.py install for mozprofile 21:45:40 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:45:40 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:45:41 INFO - Running setup.py install for mozrunner 21:45:41 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:45:41 INFO - Running setup.py install for mozscreenshot 21:45:41 INFO - Running setup.py install for moztest 21:45:41 INFO - Running setup.py install for mozversion 21:45:41 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:45:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:45:41 INFO - Cleaning up... 21:45:41 INFO - Return code: 0 21:45:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:45:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 21:45:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:45:41 INFO - Using env: {'DISPLAY': ':0', 21:45:41 INFO - 'HOME': '/home/cltbld', 21:45:41 INFO - 'LANG': 'en_US.UTF-8', 21:45:41 INFO - 'LANGUAGE': 'en_US:en', 21:45:41 INFO - 'LOGNAME': 'cltbld', 21:45:41 INFO - 'MAIL': '/var/mail/cltbld', 21:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:41 INFO - 'MOZ_NO_REMOTE': '1', 21:45:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:41 INFO - 'NO_EM_RESTART': '1', 21:45:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:41 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:41 INFO - 'SHELL': '/bin/bash', 21:45:41 INFO - 'SHLVL': '1', 21:45:41 INFO - 'TERM': 'linux', 21:45:41 INFO - 'TMOUT': '86400', 21:45:41 INFO - 'USER': 'cltbld', 21:45:41 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:41 INFO - '_': '/tools/buildbot/bin/python'} 21:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:45:41 INFO - Running setup.py (path:/tmp/pip-dw9VfC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 21:45:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:45:41 INFO - Running setup.py (path:/tmp/pip-f0wTwf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 21:45:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:45:41 INFO - Running setup.py (path:/tmp/pip-KXLzBB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 21:45:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:45:41 INFO - Running setup.py (path:/tmp/pip-dZdjQ8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:45:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:45:41 INFO - Running setup.py (path:/tmp/pip-mLH6Bn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:45:42 INFO - Running setup.py (path:/tmp/pip-9LCP7A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:45:42 INFO - Running setup.py (path:/tmp/pip-kA15Gz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:45:42 INFO - Running setup.py (path:/tmp/pip-i3NbAP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:45:42 INFO - Running setup.py (path:/tmp/pip-GR6gXz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:45:42 INFO - Running setup.py (path:/tmp/pip-2_bvuY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:45:42 INFO - Running setup.py (path:/tmp/pip-8NRfod-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:45:42 INFO - Running setup.py (path:/tmp/pip-2sjzzg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:45:42 INFO - Running setup.py (path:/tmp/pip-ceerlg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:45:42 INFO - Running setup.py (path:/tmp/pip-dRK8lm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:45:42 INFO - Running setup.py (path:/tmp/pip-fKWvXQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:45:42 INFO - Running setup.py (path:/tmp/pip-HHxkdO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 21:45:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:45:42 INFO - Running setup.py (path:/tmp/pip-6l3yzU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:45:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:44 INFO - Downloading blessings-1.6.tar.gz 21:45:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:45:44 INFO - Installing collected packages: blessings 21:45:44 INFO - Running setup.py install for blessings 21:45:44 INFO - Successfully installed blessings 21:45:44 INFO - Cleaning up... 21:45:44 INFO - Return code: 0 21:45:44 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:45:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:45:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:45:45 INFO - Reading from file tmpfile_stdout 21:45:45 INFO - Current package versions: 21:45:45 INFO - argparse == 1.2.1 21:45:45 INFO - blessings == 1.6 21:45:45 INFO - blobuploader == 1.2.4 21:45:45 INFO - docopt == 0.6.1 21:45:45 INFO - manifestparser == 1.1 21:45:45 INFO - mozInstall == 1.12 21:45:45 INFO - mozcrash == 0.16 21:45:45 INFO - mozdebug == 0.1 21:45:45 INFO - mozdevice == 0.47 21:45:45 INFO - mozfile == 1.2 21:45:45 INFO - mozhttpd == 0.7 21:45:45 INFO - mozinfo == 0.9 21:45:45 INFO - mozleak == 0.1 21:45:45 INFO - mozlog == 3.1 21:45:45 INFO - moznetwork == 0.27 21:45:45 INFO - mozprocess == 0.22 21:45:45 INFO - mozprofile == 0.27 21:45:45 INFO - mozrunner == 6.11 21:45:45 INFO - mozscreenshot == 0.1 21:45:45 INFO - mozsystemmonitor == 0.0 21:45:45 INFO - moztest == 0.7 21:45:45 INFO - mozversion == 1.4 21:45:45 INFO - psutil == 3.1.1 21:45:45 INFO - requests == 1.2.3 21:45:45 INFO - wsgiref == 0.1.2 21:45:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 21:45:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:45:45 INFO - Using env: {'DISPLAY': ':0', 21:45:45 INFO - 'HOME': '/home/cltbld', 21:45:45 INFO - 'LANG': 'en_US.UTF-8', 21:45:45 INFO - 'LANGUAGE': 'en_US:en', 21:45:45 INFO - 'LOGNAME': 'cltbld', 21:45:45 INFO - 'MAIL': '/var/mail/cltbld', 21:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:45 INFO - 'MOZ_NO_REMOTE': '1', 21:45:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:45 INFO - 'NO_EM_RESTART': '1', 21:45:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:45 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:45 INFO - 'SHELL': '/bin/bash', 21:45:45 INFO - 'SHLVL': '1', 21:45:45 INFO - 'TERM': 'linux', 21:45:45 INFO - 'TMOUT': '86400', 21:45:45 INFO - 'USER': 'cltbld', 21:45:45 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:45 INFO - '_': '/tools/buildbot/bin/python'} 21:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 21:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 21:45:45 INFO - Cleaning up... 21:45:45 INFO - Return code: 0 21:45:45 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 21:45:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:45:45 INFO - Using env: {'DISPLAY': ':0', 21:45:45 INFO - 'HOME': '/home/cltbld', 21:45:45 INFO - 'LANG': 'en_US.UTF-8', 21:45:45 INFO - 'LANGUAGE': 'en_US:en', 21:45:45 INFO - 'LOGNAME': 'cltbld', 21:45:45 INFO - 'MAIL': '/var/mail/cltbld', 21:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:45 INFO - 'MOZ_NO_REMOTE': '1', 21:45:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:45 INFO - 'NO_EM_RESTART': '1', 21:45:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:45 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:45 INFO - 'SHELL': '/bin/bash', 21:45:45 INFO - 'SHLVL': '1', 21:45:45 INFO - 'TERM': 'linux', 21:45:45 INFO - 'TMOUT': '86400', 21:45:45 INFO - 'USER': 'cltbld', 21:45:45 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:45 INFO - '_': '/tools/buildbot/bin/python'} 21:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:45 INFO - Downloading/unpacking jsonschema 21:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:45:47 INFO - Downloading/unpacking functools32 (from jsonschema) 21:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:47 INFO - Downloading functools32-3.2.3-2.tar.gz 21:45:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:45:47 INFO - warning: no files found matching '*.txt' 21:45:47 INFO - no previously-included directories found matching 'build' 21:45:47 INFO - no previously-included directories found matching 'dist' 21:45:47 INFO - no previously-included directories found matching '.git*' 21:45:47 INFO - Installing collected packages: jsonschema, functools32 21:45:47 INFO - Running setup.py install for functools32 21:45:47 INFO - warning: no files found matching '*.txt' 21:45:47 INFO - no previously-included directories found matching 'build' 21:45:47 INFO - no previously-included directories found matching 'dist' 21:45:47 INFO - no previously-included directories found matching '.git*' 21:45:47 INFO - Successfully installed jsonschema functools32 21:45:47 INFO - Cleaning up... 21:45:47 INFO - Return code: 0 21:45:47 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 21:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0e8a0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15de8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16ab660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:45:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 21:45:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:45:47 INFO - Using env: {'DISPLAY': ':0', 21:45:47 INFO - 'HOME': '/home/cltbld', 21:45:47 INFO - 'LANG': 'en_US.UTF-8', 21:45:47 INFO - 'LANGUAGE': 'en_US:en', 21:45:47 INFO - 'LOGNAME': 'cltbld', 21:45:47 INFO - 'MAIL': '/var/mail/cltbld', 21:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:47 INFO - 'MOZ_NO_REMOTE': '1', 21:45:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:47 INFO - 'NO_EM_RESTART': '1', 21:45:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:47 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:47 INFO - 'SHELL': '/bin/bash', 21:45:47 INFO - 'SHLVL': '1', 21:45:47 INFO - 'TERM': 'linux', 21:45:47 INFO - 'TMOUT': '86400', 21:45:47 INFO - 'USER': 'cltbld', 21:45:47 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:47 INFO - '_': '/tools/buildbot/bin/python'} 21:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:48 INFO - Downloading/unpacking flake8 21:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:45:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:45:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 21:45:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:45:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:45:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:45:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:45:50 INFO - no previously-included directories found matching 'docs/_build' 21:45:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:45:50 INFO - Running setup.py install for pep8 21:45:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:45:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:45:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:45:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:45:50 INFO - no previously-included directories found matching 'docs/_build' 21:45:50 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 21:45:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:45:50 INFO - Cleaning up... 21:45:50 INFO - Return code: 0 21:45:50 INFO - Running post-action listener: _resource_record_post_action 21:45:50 INFO - Running post-action listener: _start_resource_monitoring 21:45:50 INFO - Starting resource monitoring. 21:45:50 INFO - ##### 21:45:50 INFO - ##### Running install step. 21:45:50 INFO - ##### 21:45:50 INFO - Running pre-action listener: _resource_record_pre_action 21:45:50 INFO - Running main action method: install 21:45:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:45:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:45:51 INFO - Reading from file tmpfile_stdout 21:45:51 INFO - Detecting whether we're running mozinstall >=1.0... 21:45:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:45:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:45:51 INFO - Reading from file tmpfile_stdout 21:45:51 INFO - Output received: 21:45:51 INFO - Usage: mozinstall [options] installer 21:45:51 INFO - Options: 21:45:51 INFO - -h, --help show this help message and exit 21:45:51 INFO - -d DEST, --destination=DEST 21:45:51 INFO - Directory to install application into. [default: 21:45:51 INFO - "/builds/slave/test-pgo"] 21:45:51 INFO - --app=APP Application being installed. [default: firefox] 21:45:51 INFO - mkdir: /builds/slave/test-pgo/build/application 21:45:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 21:45:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 21:46:05 INFO - Reading from file tmpfile_stdout 21:46:05 INFO - Output received: 21:46:05 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:46:05 INFO - Running post-action listener: _resource_record_post_action 21:46:05 INFO - ##### 21:46:05 INFO - ##### Running run-tests step. 21:46:05 INFO - ##### 21:46:05 INFO - Running pre-action listener: _resource_record_pre_action 21:46:05 INFO - Running main action method: run_tests 21:46:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:46:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:46:05 INFO - Python 2.7.3 21:46:05 INFO - Return code: 0 21:46:05 INFO - grabbing minidump binary from tooltool 21:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173a3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x173b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:46:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 21:46:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:46:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:46:05 INFO - Return code: 0 21:46:05 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:46:05 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:46:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:46:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:46:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:46:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 21:46:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 21:46:06 INFO - Return code: 0 21:46:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 21:46:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --webServer localhost 21:46:06 INFO - Using env: {'DISPLAY': ':0', 21:46:06 INFO - 'HOME': '/home/cltbld', 21:46:06 INFO - 'LANG': 'en_US.UTF-8', 21:46:06 INFO - 'LANGUAGE': 'en_US:en', 21:46:06 INFO - 'LOGNAME': 'cltbld', 21:46:06 INFO - 'MAIL': '/var/mail/cltbld', 21:46:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:46:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:46:06 INFO - 'MOZ_NO_REMOTE': '1', 21:46:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:46:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:46:06 INFO - 'NO_EM_RESTART': '1', 21:46:06 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:46:06 INFO - 'PWD': '/builds/slave/test-pgo', 21:46:06 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:46:06 INFO - 'SHELL': '/bin/bash', 21:46:06 INFO - 'SHLVL': '1', 21:46:06 INFO - 'TERM': 'linux', 21:46:06 INFO - 'TMOUT': '86400', 21:46:06 INFO - 'USER': 'cltbld', 21:46:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682', 21:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:46:06 INFO - '_': '/tools/buildbot/bin/python'} 21:46:06 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost'] with output_timeout 3600 21:46:07 INFO - mozversion INFO | application_buildid: 20160302191720 21:46:07 INFO - mozversion INFO | application_changeset: 7b0d105d7da5e29bba9aca76c6d6a3f3cded0202 21:46:07 INFO - mozversion INFO | application_display_name: Nightly 21:46:07 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:46:07 INFO - mozversion INFO | application_name: Firefox 21:46:07 INFO - mozversion INFO | application_remotingname: firefox 21:46:07 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:46:07 INFO - mozversion INFO | application_vendor: Mozilla 21:46:07 INFO - mozversion INFO | application_version: 45.0 21:46:07 INFO - mozversion INFO | platform_buildid: 20160302191720 21:46:07 INFO - mozversion INFO | platform_changeset: 7b0d105d7da5e29bba9aca76c6d6a3f3cded0202 21:46:07 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:46:07 INFO - mozversion INFO | platform_version: 45.0 21:46:07 INFO - 2016-03-02 21:46:07,148 DEBUG : using testdate: 1456983967 21:46:07 INFO - 2016-03-02 21:46:07,148 DEBUG : actual date: 1456983967 21:46:07 INFO - 2016-03-02 21:46:07,158 INFO : starting webserver on 'localhost:48196' 21:46:07 INFO - 2016-03-02 21:46:07,158 INFO : Starting test suite talos-linux64-ix-039 21:46:07 INFO - 2016-03-02 21:46:07,158 INFO : Starting test dromaeo_css 21:46:07 INFO - 2016-03-02 21:46:07,159 DEBUG : operating with platform_type : linux_ 21:46:07 INFO - 2016-03-02 21:46:07,159 INFO : Initialising browser for dromaeo_css test... 21:46:07 INFO - 2016-03-02 21:46:07,178 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgiLmCo/profile http://localhost:48196/getInfo.html 21:46:15 INFO - 2016-03-02 21:46:15,027 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:46:15 INFO - 2016-03-02 21:46:15,028 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:46:15 INFO - 2016-03-02 21:46:15,028 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:46:15 INFO - 2016-03-02 21:46:15,028 DEBUG : BROWSER_OUTPUT: __metrics 21:46:15 INFO - 2016-03-02 21:46:15,258 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:46:16 INFO - 2016-03-02 21:46:16,476 INFO : Browser initialized. 21:46:16 INFO - 2016-03-02 21:46:16,477 INFO : Running cycle 1/1 for dromaeo_css test... 21:46:16 INFO - 2016-03-02 21:46:16,477 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgiLmCo/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:46:28 INFO - 2016-03-02 21:46:28,142 DEBUG : BROWSER_OUTPUT: RSS: Main: 132227072 21:46:28 INFO - 2016-03-02 21:46:28,142 DEBUG : BROWSER_OUTPUT: 21:47:18 INFO - 2016-03-02 21:47:18,952 DEBUG : BROWSER_OUTPUT: 1456984038951 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:49:27 INFO - 2016-03-02 21:49:27,544 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48196/tests/dromaeo/cssquery-ext.html) 21:49:28 INFO - 2016-03-02 21:49:28,555 DEBUG : BROWSER_OUTPUT: RSS: Main: 230887424 21:49:28 INFO - 2016-03-02 21:49:28,555 DEBUG : BROWSER_OUTPUT: 21:52:30 INFO - 2016-03-02 21:52:30,595 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/dromaeo/cssquery-ext.html (next: http://localhost:48196/tests/dromaeo/cssquery-jquery.html) 21:52:31 INFO - 2016-03-02 21:52:31,161 DEBUG : BROWSER_OUTPUT: RSS: Main: 218218496 21:52:31 INFO - 2016-03-02 21:52:31,161 DEBUG : BROWSER_OUTPUT: 21:55:32 INFO - 2016-03-02 21:55:32,967 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48196/tests/dromaeo/cssquery-mootools.html) 21:55:33 INFO - 2016-03-02 21:55:33,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 216907776 21:55:33 INFO - 2016-03-02 21:55:33,534 DEBUG : BROWSER_OUTPUT: 21:58:35 INFO - 2016-03-02 21:58:35,216 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48196/tests/dromaeo/cssquery-prototype.html) 21:58:35 INFO - 2016-03-02 21:58:35,782 DEBUG : BROWSER_OUTPUT: RSS: Main: 196812800 21:58:35 INFO - 2016-03-02 21:58:35,782 DEBUG : BROWSER_OUTPUT: 22:01:38 INFO - 2016-03-02 22:01:38,125 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48196/tests/dromaeo/cssquery-yui.html) 22:01:38 INFO - 2016-03-02 22:01:38,694 DEBUG : BROWSER_OUTPUT: RSS: Main: 200982528 22:01:38 INFO - 2016-03-02 22:01:38,694 DEBUG : BROWSER_OUTPUT: 22:04:40 INFO - 2016-03-02 22:04:40,904 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/dromaeo/cssquery-yui.html (next: http://localhost:48196/tests/dromaeo/cssquery-dojo.html) 22:04:41 INFO - 2016-03-02 22:04:41,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 213008384 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3272;3346.531485592019;3375.4599264117733;3381.0026042317513;3392.864285428583;5491.368492623349;5507.64200326979;5517.724113794305;5533.418991005947;5557.805476808313;5531.115021596545;5532.695701736403;5545.195946587753;5573.470520300575;5577.135543990677;4974.303597496353;5045.394552653677;5078.746062696869;5081.364829396325;5138.845834624948;5022.372203474566;5092.159793634046;5092.898142037157;5094.414142373619;5101.209312556541;4940.1848694965465;4993.226049962262;5058.721770302632;5065.113605119108;5096.057229412552;5073.46728593496;5089.440161582242;5090.669106508065;5091.312672789181;5105.566026887682;4761.904761904742;4810.8316208932865;5007.39911210657;5008.223725322581;5063.8480845574695;5129.3075434816565;5264.473552644743;5282.709450980195;5284.128118860383;5284.365876094893;5873.412658734135;5903.35063143054;5964.850878728023;5970.1343305220935;5970.492508136814;5683.772649094031;5817.592768506164;5927.673977931212;5969.343372229051;5988.28140623128;5618.438156184389;5630.859228519279;5631.408702086263;5634.549236061105;5635.69003704792;6046.818595442144;6062.090686397076;6063.393660633901;6064.545159112996;6069.423404776539;5668;5713;5732.71336433185;5841.561882858802;5845.210896528999;5918.822435326947;5923.259592550931;5929.822105336847;5929.911051334234;5947.821565353046;5893.675847828325;5900.557458190653;5908.113782932594;5909.704514774244;5915.438033386821;5680.40355762639;5768.682722450221;5788.479036886701;5844.357120716718;5882.205902203234;4462.509123996358;4477.395551600557;4478.261086920622;4493.415855938707;4497.752623605667;3954.307996100671;4039.434479172918;4041.7372870763306;4057.5739547347102;4073.1853629274265;5526.613137080446;5544.805931792449;5631.239782629293;5669.801556945569;5691.089425691952;5939.673317967553;5960.284765828128;5965.224520812267;5966;5972.074328479092;5429.294191755047;5617.101263797773;5630.8029218977135;5636.464535869086;5648.548116150698;4820.25286080658;4911.45973942866;5017.799288028438;5018.824341148043;5035.345405097314;6057.8485537861025;6069.514438844882;6073.301570319381;6074.483668888106;6079.939200608026;5666.914996275142;5827.358990510956;5976.282846058501;5978.581499295007;5978.790742323998;5955.880882382415;6040.546958978006;6046.486048685911;6046.637201767908;6086.421789929949;5846.853828654148;5848.122781582797;5900.409959004065;5902.1146827976145;5915.763369465173;5734.827955161351;5821.359650438533;5825.825225243249;6007.038873780174;6022.518198544019;5836.533077353886;5848.64908105515;5852.7658893643775;5872.29532456108;5875.529957603467;5826.242588463472;5867;5876.588638795243;5882.411758824083;5882.676452795137;5554.527865131509;5604.747786349542;5635.351934527418;5719.771209151588;5781.479666829838;5842.474177324061;5868.471837534642;5887.1757953885635;5897;5906.143609176559;5715.8856822864145;5757.568182386254;5768.971155144198;5773.1051686988585;5773.566982476247 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: |1;ext.html;611.2786911444493;641.4547634510669;647.8801421736978;657.4707360574736;694.5069001009283;32666.346673066524;40636;41271;41329;41367.79316103423;45724.77137614316;46145;46163;46188.538114618845;46358.07283854321;8059.274665280123;8183.754487365389;9131.178193962542;9297.256219502424;9302.302327325444;10455.163586913066;10618.362898226093;10619.044286014258;10628.574857005711;10628.627998020032;10823.621173258938;10916.890831091707;10929.071028962548;10929.781404371908;10931.01620854123;10738.140948724084;10798.028177464066;10801.67594972152;10814.243002989793;10832.566697332097;10693.572257109707;10818.459077046195;10819.188560857967;10821.83767243492;10826.18803589734;43266.567334326566;43641.34537981933;43694.78152609248;43714;43821.34267985983;3763.0216143802536;3837.9424308635394;4010.3382941814566;4147.730397524181;4152.875413737593;32916.177095572566;33081.17297067568;33322.166945826306;33567;33692;4220.53574106848;4270.274053410896;4285.807138678767;4304.375865499485;4334.761588112652;309452;309994;310051;310228;310416;57912;59509.702451487465;64656.35343646597;65539;65550.67224663847;58437.70781146067;58845.117323240185;58877.116843247386;58917.70541147267;59004.7049764757;16400.835991640168;16471.83528164703;16557.58606034859;16566.751498727528;16568.83431165697;12119.212251203644;12162.574309899288;12174.208676436003;12176.512939482498;12191.146619736533;20504.69242961357;21474.14103435845;21659.458513537287;21693.240736574146;21695.132194712034;7962.522248665098;8089.878651820228;8116.837663246702;8168.183181681784;8227.13615070421;983.3804703037184;1045.5713157605346;1072.8712554493354;1077.7305673581604;1100.5597760895705;29598.260043499085;29732;30740.53889191664;30808.845955770077;30919.536206956913;1106.4744246482855;1138.9829152562552;1207.6558180918396;1241.1002023532867;1278.0733967873218;13479.989000824977;15109.266584527513;15576.221188940508;16025.358985640676;16572.337106515846;387541;397215;398234;398626;399799;57109.71445142748;57187;57443;57551.13673294904;57693.423065769646;1340.9597712068653;1355.5255660518937;1475.8007668964767;1500.3698446652431;1576.8501992310712;26878.193654190407;27009;27417.72582274152;27466.725332746417;28010.85994570014;46541.3018804725;47233.52766472291;47242.29136562886;47294.52705472901;47420.52579474161;253340;254840;255329;256702;257731;34670.133246668025;35247;35275.29449411049;35523.11192220215;35536.46695299624;40317.79841100776;40545.391819122146;40634;41185.38221926733;41789;35309.646903530636;35318;35442.468362975094;36041.459378109874;39014.804925975186;37532;37536.624633753316;38181.61818381781;38441.231175376895;38529.22941541098;37397.81301093477;38245.42631860469;38318.616813831504;38332.80833595814;38391;32656.34687306288;34173.82913085419;35605.28789424145;35630.465543016355;36049.63950360463;25938;26381.736182637927;26424.339391514597;26617.86691066532;26935.191944241706 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: |2;jquery.html;363.35867194401885;363.38043635601304;378.7840930669519;381.555487856647;388.10928918132885;15349.923250383761;17091.572710682223;17127;18415;18973.241070357188;10828.133749300057;10849.511771970261;13646.590602281947;17139.31442742292;17474.475765727017;5011.924821127677;5135.537801597856;5136.8972620547565;5137.2037334213255;5141.640085194038;4093.9590604094024;4206.64243539298;5441.972790136064;5568.443155684439;5577.972110139443;5514.200440936062;5536.390996990328;5539.335279766434;5541.806036788713;5555.722213889309;5268.5521730652945;5419.810306639249;5424;5429.619926605139;5442.346918369801;5236.92144617831;5280.867978300535;5609;5612.15817627355;5616.129499927518;19766.604667906562;19810.50473738168;19846.404607861787;20191;20356.18575256988;2259.9830076465655;2280.3501002214457;2305.124052859919;2315.409570559513;2334.054707843326;9840.409575425498;10070.69787906364;12626.747465050648;13248;14125.505607303696;1959.5199176201922;1968;2001.4095841726726;2019.545602239499;2024.180207016161;62501.37498625001;64453.3554664452;64884.99999999953;65568.3443165567;66476.3352366475;10680.198985076151;10894;10947.671569852917;10958.452077396098;11014.39420831862;14341.426342946374;14415.42338306477;14438.494652687106;14449.566513004627;14462.421503139756;8403.285720713686;8424.705135320235;8442.240198382053;8470.280026197843;8473.745787626382;8293.751187464386;8299.8340033199;8324.917760691193;8356.373272004503;8377.664893404317;4642.141203877293;4730.219513780256;4733.763311834395;4737.028909073631;4742.288656701522;1764.0953032397338;1776.3782676063533;1814.8003719590833;1927.5373910261717;1952.5509132899228;990.6631745206664;1022.4172221833633;1056.3292309383596;1068.8396740488834;1092;17043.14784260782;17578.208980595904;17766.911165444348;18231.72652410215;18232.817671823377;886.2511178054533;978.0122076702581;1005.4269066631949;1035.177034257767;1041.4948749856314;9281;9413.105754953269;9453.527323633929;9464.90535094654;9596;662949;663851;663988;670904;706062;84585.15414845773;84629.57685211535;85151.57424212963;85328;86850.56574717085;1204.5242129358853;1208.323338930202;1212.8665846756674;1217.5860207529483;1230.4155526125019;15103.39586416531;15125.319360628579;15146.697066058838;15462.536123916301;15629.765553516934;34763.65236347604;35117.29765404729;35154.999999998974;35826.64173358233;35830.82084589561;50168.24747628861;51420.74289628528;51986.740066299426;52037;55106.44893551013;10383.221258405802;10481.056704896597;10499.212559058255;10642.73393165177;10660.25378223517;14011;16187.352505899633;17588.560285992953;18337.449876503728;18700.15849286758;13651.317434128452;14614.70770584561;15028.248587570797;15433.074015559103;15799.763003554726;14161.858381416467;14705.264736763333;15021.399144034553;15031.624209394853;15351.769723454381;15076.228401122578;15095.245237738289;15912;15934.601634958755;16155.111468869325;9926.652567160114;9932.25508086882;9995.300328976902;10041.04610062043;10052.748681283027;10630;10662.413567253565;12983.35083245853;15013.6997260052;15295.770563441334 22:04:41 INFO - 2016-03-02 22:04:41,163 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1522.048719550281;1766.2405165778716;1802.546470345123;1818.1909050472536;1824.9087545622715;6811.863762724743;6976.616286104262;7501.962490187549;7685.500442471235;7685.654145563449;7236.457265705067;7408.185099639036;7447.664855081521;7453.366463850566;7475.102987641477;2279.623862062762;2311.318161142467;2314.467672435341;2336.906523739048;2339.3449834046496;2007.0265921028363;2040.7449068866392;2283.086765293879;2317.9536409271805;2342.1685301717816;2264.094362255095;2273.9658905116435;2277.3964899301614;2289.404754763757;2297.9195728149516;2272.261515007621;2284.657301404786;2285.417218609244;2288.0733303012225;2299.988500057506;2269.829762767799;2277.430642339415;2286.4055345610095;2286.6798648189265;2287.1194590082773;7540.886886696704;7621.733239336652;7629.7329593463965;7657.081150261948;7670.616469176574;4635.652326075524;4727.929081063787;4731.574158325767;4733.0533893221145;4733.763311834395;3080.8151510909415;3096.5355196720448;3144.7641426893074;3148.763842711806;3160.5259211118514;1035.8498017787454;1054.8259537176357;1070.5343175718588;1075.8493810866407;1114.8662160540787;10374.325668831501;10377.740556486147;10384.428856412895;10390.84413733787;10402.635907743268;4222.408862759216;4233.216854881856;4250.957490425118;4296.5273819879785;4299.828006879752;8992.145746154105;8995.415298005608;8997.595108220146;9002.63989440415;9004.459732415946;4756.262779269149;4770.189067858472;4773.522647735268;4781.760911954389;4786.473487916326;4326.329418940069;4367.65058795302;4367.995361066973;4372.693911426233;4375.584319489643;3694.113412780913;3746.2132952079824;3788.677962373229;3815.561210460777;3818.1790914952862;1108.2740804772886;1133.637236084445;1176.5411489519095;1193.659806955031;1212.1938910624513;1089.7874914391618;1103.2994048779083;1112.5049353037982;1156.306216270231;1156.4680247086435;7396.778096657109;7449.627518624047;7468.103827540621;7478.738244161428;7495.962520187364;1109.1570406490964;1127.3799410324289;1131.8981291683788;1141.8344340070644;1164.126904821384;5840.2115714378015;5953.077273022688;5955.731992060368;5956.940430595725;5964.433378829004;8844;9166.724998250063;9483.099105584959;9487.715368538955;9496.810063798684;9385.436873787594;9395.483248421402;9409.059094090537;9410.43537387759;9418.576164072632;7129.322714342233;7322.450816188807;7325.743598974117;7325.816854578785;7497.9250207497225;7455.664495097625;7473.8878916817375;7553.5845528494265;7558.735444259424;7561.168271490022;8410.074891762024;8616.612252448529;8648.481091134552;8672;8673.392862499564;8619.784505387415;8672.392932494664;8806.955965220133;8823.205911467998;8827.735167944971;7008.684609192496;7060.3645671887725;7090.787276381717;7095.574265544084;7109.715611375694;6669;7533.660985255787;7541.924580754122;7573.242675732383;7576.545407275581;6578.440832529289;7009.684564194726;7208.135023797178;7289.8177545559665;7309.817254568678;7180.712771489082;7235.927640723526;7241.674124664298;7285.854282914418;7350.7427240046345;7256.346928776436;7279.8544029120185;7289.015982842248;7289.343959043712;7291.30732580404;6763.154605674291;6817;6877.552959057645;6898.827529311808;6900.378965893094;6850.143732033496;6920.134983127109;6922.030915671709;6923;6943.479239057192 22:04:41 INFO - 2016-03-02 22:04:41,167 DEBUG : BROWSER_OUTPUT: |4;prototype.html;353.9522164507791;354.65952685421996;360.6339565341178;361.8317482370697;362.5957057880464;6057.454829065383;6101.572889897703;6114.633122012677;6132.662703551303;6155.415235552616;5913.793017244398;6035;6045.214122164123;6047.939520604793;6049.485793707529;4710.599599034069;4717.221658426372;4723.480417154119;4724;4731.33761273422;4739.668223224376;4740.288956656512;4740.976295118537;4744.857654270378;4748.90502189956;4555.180067587833;4660.137874493213;4662.417197850269;4671.3460115583675;4701.271302948031;4658.161530924433;4658.510856360084;4661.953380466186;4662.860114196579;4684.273937539704;3946.5856085110936;4038.353863381845;4123.175364926997;4124.154548317618;4131.091159944837;5671.432856714296;5737.3688894221605;5779.450952159538;5794.449527294901;5811.012127938218;5436.809711660073;5442.564594832404;5451.918221226685;5453.045716999509;5461.371942226655;6355.872882542323;6471.352864713491;6474.093626892239;6480.805575832733;6500.089987401768;4545.840895568639;4549.840755573539;4552.089582083597;4552.385427967249;4560;6617.602943823386;6692.464602831762;6695.163104611972;6704.865902681919;6708.060871477997;4681.7425041622355;4686.554777296152;4690.718556886598;4692.6245900327895;4693.389859318267;4639.095376401637;4748.881277968009;4755.881102972453;4756.453007904066;4763.356946812205;5424.240606315119;5427.4301198373605;5427.782888684487;5433.130699088206;5437.891242175135;6502.967485162639;6511.804645860631;6513.674316284261;6518.511111666644;6520.0871877937125;1650.026484374225;1676.0949087492618;1677.8238284979902;1682.5793551612096;1684.8652107831344;1089.743910181107;1097.7145942054963;1116.3525155409843;1128.2158899564888;1136.091127098318;6343;6354.650494222769;6355.428011478989;6356.173697419398;6356.745730170742;6680.899786503206;6711.966440167866;6716.059751634775;6731.057651928734;6731.663416829139;6269.216347956505;6350.872982540323;6353.682315884187;6357.11000459936;6359.300476947532;3646.197836475971;3679.7240206984584;3680.7607505512055;3682.8895133146048;3684.299983003221;6679.966600166968;6801.591904485746;6810.965945170342;6881.277465866112;6953.296442371651;6967.721291148398;6982.685779139951;6987.580745155307;6988.091548098816;7004.15950085993;6796.422304104207;6986.476014298948;6989.126359205099;7001;7003.754868579677;6785.457163426815;6814.352636499525;6818.113645226089;6821.693023814039;6823.215330237086;6704.932950670431;6716.1604799400075;6722.932770672426;6725.9663701681175;6730.865382692221;6831.658417079226;6988.755393561404;7005.05431766705;7023.33278338557;7024.086868707035;4707.152712511644;4751.049790041936;4754.072955773591;4754.738489383117;4756.19144745401;6770.461077096089;6796.456283497408;6808.897866531907;6811.5231933766145;6815.011823285695;6490.935090649033;6564.6389448580785;6597.60414375139;6628.171478565179;6638.137042184679;6547.181602299713;6606.669666516751;6613.470922326299;6621.735130594722;6622.768203112868;6493.155889734304;6493.967530162319;6496.155499785186;6501.739930402731;6506.934930650633;6516.185476815398;6609.735610575523;6649.301823308483;6651.933480665131;6657.167854018247;6427.196600424947;6474.0612611172055;6476.384743449365;6498.317676643885;6503.479721622353 22:04:41 INFO - 2016-03-02 22:04:41,168 DEBUG : BROWSER_OUTPUT: |5;yui.html;879.2306731609841;884.211775643625;889.09756597054;889.1330952321483;891.5943245823152;14318;14894.255287235648;14918.925405372971;14932.178730169837;14958.551243462714;14160.50438234662;14219.573412797607;14244.14535127893;14266.429342826275;14274.214918179521;721.3651986252091;723.5875550935968;853.9359548033907;868.7176667583029;877.1623099939543;878.2359347367799;879.5338470610586;886.7650072730731;887.1217494680285;887.2148148888216;919.8482250428705;927.4713413354391;928.067292371166;928.1739252065667;928.6099838068028;755.0184759812249;762.8169239382536;904.267543289934;918.1507105927058;918.8529835226399;677.6814896998452;679.0832376292016;680.0920770771027;680.1022650101868;680.4216416046321;14129.858701412957;14270.643233919234;14276.643083922881;14288.57134285973;14301.141931484144;13132.6716832079;13850.93074534631;13893.305334733222;13911.79132313016;14032.08791428554;10890.455477226107;11134.49894754738;11282.943585282103;11329.263597866113;11364.431778411046;3861.6524512793703;3951.8616848410456;3958.604139586047;3978.4629075074786;4011.2378648056774;50295.49704502945;52221;53265;53283;53533.197002045;10609.310394824312;10691.518881650345;10716.624918127829;10817.134629229642;10999.01008909186;19300;19332.516687082654;19342.613147737247;19395;19428.417147485598;7965.721199757981;8056.154103819132;8096.878546821802;8118.553479558681;8213.425060245845;3483.0247530691445;3559.4660800879556;4244.745315281093;4471.150481408522;4518.073794872043;3095.133362658459;3130.6712795156636;3134.7335476484755;3143.0570828751283;3146.3549972255632;538.4292649791156;553.3829779795499;553.9695316757617;565.8245943757448;565.9094544872883;617.5491890919653;660.5607271164622;661.9536632435684;668.3984414027317;674.8852695041854;8573.828523429496;8964.346687770927;8969.14793094655;9015.639374424949;9090.954545227232;524.2110623511591;627.5607075047485;642.7204166187723;648.2998361769372;662.6189940784069;4267.8292868284925;4306.698531102793;4461.955380446219;4495.685302028892;4516.16450956574;59560.70219648874;59860.70069649711;59869.40130598638;63170.68414657897;65384;54440;54643.72678136584;56361.43638563562;56862.43137568654;56880.43119568834;49782.0043599126;50857.74571127195;50924.236136457985;51134.23298650523;52034.21948670773;8959.41763785352;8985.955070224607;9117.635294588143;9389.530523473662;9479;38673.80663096667;39278.6072139275;39827.40258896061;40272.597274026884;40584.188316234104;46551.53448465472;50272.497275026784;50385.74807125941;50627;51008.234876477625;10863.293885897392;10911.399873007062;10913.39976301311;10971.561137554409;11280.548778049115;11794.46924888365;12348.876511234772;12648.114631976041;12662.936685316514;14246.287685615885;11655.242409243372;11831.05351571855;11907.285562866256;12372.690682732644;12841.165324253894;10869.239153259196;11689.240199387013;11698.532058717556;11928.284302941851;12231.571894983988;9221.308401869752;11020.50407731638;11249.381284029458;11442.256253343507;11498.48256828428;10219.386836789816;10273.9486302568;10307.484625768831;10373.948130259301;10408.947955260175;9414.388064775767;9739.464329461947;9750.463724495221;9762.219022478042;10042.196624269895 22:04:41 INFO - 2016-03-02 22:04:41,168 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:04:41 INFO - 2016-03-02 22:04:41,168 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:04:41 INFO - 2016-03-02 22:04:41,168 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,774 22:04:41 INFO - 2016-03-02 22:04:41,168 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:04:41 INFO - 2016-03-02 22:04:41,168 DEBUG : BROWSER_OUTPUT: __startTimestamp1456985081155__endTimestamp 22:04:41 INFO - 2016-03-02 22:04:41,169 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,169 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:04:41 INFO - 2016-03-02 22:04:41,169 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:04:41 INFO - 2016-03-02 22:04:41,169 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,169 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5490.45 Median:5713.00 stddev:612.20 (10.7%) stddev-sans-first:588.82 22:04:41 INFO - 2016-03-02 22:04:41,170 DEBUG : BROWSER_OUTPUT: Values: 3272.0 3346.5 3375.5 3381.0 3392.9 5491.4 5507.6 5517.7 5533.4 5557.8 5531.1 5532.7 5545.2 5573.5 5577.1 4974.3 5045.4 5078.7 5081.4 5138.8 5022.4 5092.2 5092.9 5094.4 5101.2 4940.2 4993.2 5058.7 5065.1 5096.1 5073.5 5089.4 5090.7 5091.3 5105.6 4761.9 4810.8 5007.4 5008.2 5063.8 5129.3 5264.5 5282.7 5284.1 5284.4 5873.4 5903.4 5964.9 5970.1 5970.5 5683.8 5817.6 5927.7 5969.3 5988.3 5618.4 5630.9 5631.4 5634.5 5635.7 6046.8 6062.1 6063.4 6064.5 6069.4 5668.0 5713.0 5732.7 5841.6 5845.2 5918.8 5923.3 5929.8 5929.9 5947.8 5893.7 5900.6 5908.1 5909.7 5915.4 5680.4 5768.7 5788.5 5844.4 5882.2 4462.5 4477.4 4478.3 4493.4 4497.8 3954.3 4039.4 4041.7 4057.6 4073.2 5526.6 5544.8 5631.2 5669.8 5691.1 5939.7 5960.3 5965.2 5966.0 5972.1 5429.3 5617.1 5630.8 5636.5 5648.5 4820.3 4911.5 5017.8 5018.8 5035.3 6057.8 6069.5 6073.3 6074.5 6079.9 5666.9 5827.4 5976.3 5978.6 5978.8 5955.9 6040.5 6046.5 6046.6 6086.4 5846.9 5848.1 5900.4 5902.1 5915.8 5734.8 5821.4 5825.8 6007.0 6022.5 5836.5 5848.6 5852.8 5872.3 5875.5 5826.2 5867.0 5876.6 5882.4 5882.7 5554.5 5604.7 5635.4 5719.8 5781.5 5842.5 5868.5 5887.2 5897.0 5906.1 5715.9 5757.6 5769.0 5773.1 5773.6 22:04:41 INFO - 2016-03-02 22:04:41,170 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,170 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49889.03 Median:35313.82 stddev:85384.88 (241.8%) stddev-sans-first:85544.25 22:04:41 INFO - 2016-03-02 22:04:41,170 DEBUG : BROWSER_OUTPUT: Values: 611.3 641.5 647.9 657.5 694.5 32666.3 40636.0 41271.0 41329.0 41367.8 45724.8 46145.0 46163.0 46188.5 46358.1 8059.3 8183.8 9131.2 9297.3 9302.3 10455.2 10618.4 10619.0 10628.6 10628.6 10823.6 10916.9 10929.1 10929.8 10931.0 10738.1 10798.0 10801.7 10814.2 10832.6 10693.6 10818.5 10819.2 10821.8 10826.2 43266.6 43641.3 43694.8 43714.0 43821.3 3763.0 3837.9 4010.3 4147.7 4152.9 32916.2 33081.2 33322.2 33567.0 33692.0 4220.5 4270.3 4285.8 4304.4 4334.8 309452.0 309994.0 310051.0 310228.0 310416.0 57912.0 59509.7 64656.4 65539.0 65550.7 58437.7 58845.1 58877.1 58917.7 59004.7 16400.8 16471.8 16557.6 16566.8 16568.8 12119.2 12162.6 12174.2 12176.5 12191.1 20504.7 21474.1 21659.5 21693.2 21695.1 7962.5 8089.9 8116.8 8168.2 8227.1 983.4 1045.6 1072.9 1077.7 1100.6 29598.3 29732.0 30740.5 30808.8 30919.5 1106.5 1139.0 1207.7 1241.1 1278.1 13480.0 15109.3 15576.2 16025.4 16572.3 387541.0 397215.0 398234.0 398626.0 399799.0 57109.7 57187.0 57443.0 57551.1 57693.4 1341.0 1355.5 1475.8 1500.4 1576.9 26878.2 27009.0 27417.7 27466.7 28010.9 46541.3 47233.5 47242.3 47294.5 47420.5 253340.0 254840.0 255329.0 256702.0 257731.0 34670.1 35247.0 35275.3 35523.1 35536.5 40317.8 40545.4 40634.0 41185.4 41789.0 35309.6 35318.0 35442.5 36041.5 39014.8 37532.0 37536.6 38181.6 38441.2 38529.2 37397.8 38245.4 38318.6 38332.8 38391.0 32656.3 34173.8 35605.3 35630.5 36049.6 25938.0 26381.7 26424.3 26617.9 26935.2 22:04:41 INFO - 2016-03-02 22:04:41,171 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,171 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33218.11 Median:11107.59 stddev:110023.77 (990.5%) stddev-sans-first:110304.75 22:04:41 INFO - 2016-03-02 22:04:41,171 DEBUG : BROWSER_OUTPUT: Values: 363.4 363.4 378.8 381.6 388.1 15349.9 17091.6 17127.0 18415.0 18973.2 10828.1 10849.5 13646.6 17139.3 17474.5 5011.9 5135.5 5136.9 5137.2 5141.6 4094.0 4206.6 5442.0 5568.4 5578.0 5514.2 5536.4 5539.3 5541.8 5555.7 5268.6 5419.8 5424.0 5429.6 5442.3 5236.9 5280.9 5609.0 5612.2 5616.1 19766.6 19810.5 19846.4 20191.0 20356.2 2260.0 2280.4 2305.1 2315.4 2334.1 9840.4 10070.7 12626.7 13248.0 14125.5 1959.5 1968.0 2001.4 2019.5 2024.2 62501.4 64453.4 64885.0 65568.3 66476.3 10680.2 10894.0 10947.7 10958.5 11014.4 14341.4 14415.4 14438.5 14449.6 14462.4 8403.3 8424.7 8442.2 8470.3 8473.7 8293.8 8299.8 8324.9 8356.4 8377.7 4642.1 4730.2 4733.8 4737.0 4742.3 1764.1 1776.4 1814.8 1927.5 1952.6 990.7 1022.4 1056.3 1068.8 1092.0 17043.1 17578.2 17766.9 18231.7 18232.8 886.3 978.0 1005.4 1035.2 1041.5 9281.0 9413.1 9453.5 9464.9 9596.0 662949.0 663851.0 663988.0 670904.0 706062.0 84585.2 84629.6 85151.6 85328.0 86850.6 1204.5 1208.3 1212.9 1217.6 1230.4 15103.4 15125.3 15146.7 15462.5 15629.8 34763.7 35117.3 35155.0 35826.6 35830.8 50168.2 51420.7 51986.7 52037.0 55106.4 10383.2 10481.1 10499.2 10642.7 10660.3 14011.0 16187.4 17588.6 18337.4 18700.2 13651.3 14614.7 15028.2 15433.1 15799.8 14161.9 14705.3 15021.4 15031.6 15351.8 15076.2 15095.2 15912.0 15934.6 16155.1 9926.7 9932.3 9995.3 10041.0 10052.7 10630.0 10662.4 12983.4 15013.7 15295.8 22:04:41 INFO - 2016-03-02 22:04:41,171 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,172 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5513.23 Median:6623.72 stddev:2813.30 (42.5%) stddev-sans-first:2805.19 22:04:41 INFO - 2016-03-02 22:04:41,172 DEBUG : BROWSER_OUTPUT: Values: 1522.0 1766.2 1802.5 1818.2 1824.9 6811.9 6976.6 7502.0 7685.5 7685.7 7236.5 7408.2 7447.7 7453.4 7475.1 2279.6 2311.3 2314.5 2336.9 2339.3 2007.0 2040.7 2283.1 2318.0 2342.2 2264.1 2274.0 2277.4 2289.4 2297.9 2272.3 2284.7 2285.4 2288.1 2300.0 2269.8 2277.4 2286.4 2286.7 2287.1 7540.9 7621.7 7629.7 7657.1 7670.6 4635.7 4727.9 4731.6 4733.1 4733.8 3080.8 3096.5 3144.8 3148.8 3160.5 1035.8 1054.8 1070.5 1075.8 1114.9 10374.3 10377.7 10384.4 10390.8 10402.6 4222.4 4233.2 4251.0 4296.5 4299.8 8992.1 8995.4 8997.6 9002.6 9004.5 4756.3 4770.2 4773.5 4781.8 4786.5 4326.3 4367.7 4368.0 4372.7 4375.6 3694.1 3746.2 3788.7 3815.6 3818.2 1108.3 1133.6 1176.5 1193.7 1212.2 1089.8 1103.3 1112.5 1156.3 1156.5 7396.8 7449.6 7468.1 7478.7 7496.0 1109.2 1127.4 1131.9 1141.8 1164.1 5840.2 5953.1 5955.7 5956.9 5964.4 8844.0 9166.7 9483.1 9487.7 9496.8 9385.4 9395.5 9409.1 9410.4 9418.6 7129.3 7322.5 7325.7 7325.8 7497.9 7455.7 7473.9 7553.6 7558.7 7561.2 8410.1 8616.6 8648.5 8672.0 8673.4 8619.8 8672.4 8807.0 8823.2 8827.7 7008.7 7060.4 7090.8 7095.6 7109.7 6669.0 7533.7 7541.9 7573.2 7576.5 6578.4 7009.7 7208.1 7289.8 7309.8 7180.7 7235.9 7241.7 7285.9 7350.7 7256.3 7279.9 7289.0 7289.3 7291.3 6763.2 6817.0 6877.6 6898.8 6900.4 6850.1 6920.1 6922.0 6923.0 6943.5 22:04:41 INFO - 2016-03-02 22:04:41,172 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,172 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5474.21 Median:6306.11 stddev:1643.78 (26.1%) stddev-sans-first:1602.83 22:04:41 INFO - 2016-03-02 22:04:41,172 DEBUG : BROWSER_OUTPUT: Values: 354.0 354.7 360.6 361.8 362.6 6057.5 6101.6 6114.6 6132.7 6155.4 5913.8 6035.0 6045.2 6047.9 6049.5 4710.6 4717.2 4723.5 4724.0 4731.3 4739.7 4740.3 4741.0 4744.9 4748.9 4555.2 4660.1 4662.4 4671.3 4701.3 4658.2 4658.5 4662.0 4662.9 4684.3 3946.6 4038.4 4123.2 4124.2 4131.1 5671.4 5737.4 5779.5 5794.4 5811.0 5436.8 5442.6 5451.9 5453.0 5461.4 6355.9 6471.4 6474.1 6480.8 6500.1 4545.8 4549.8 4552.1 4552.4 4560.0 6617.6 6692.5 6695.2 6704.9 6708.1 4681.7 4686.6 4690.7 4692.6 4693.4 4639.1 4748.9 4755.9 4756.5 4763.4 5424.2 5427.4 5427.8 5433.1 5437.9 6503.0 6511.8 6513.7 6518.5 6520.1 1650.0 1676.1 1677.8 1682.6 1684.9 1089.7 1097.7 1116.4 1128.2 1136.1 6343.0 6354.7 6355.4 6356.2 6356.7 6680.9 6712.0 6716.1 6731.1 6731.7 6269.2 6350.9 6353.7 6357.1 6359.3 3646.2 3679.7 3680.8 3682.9 3684.3 6680.0 6801.6 6811.0 6881.3 6953.3 6967.7 6982.7 6987.6 6988.1 7004.2 6796.4 6986.5 6989.1 7001.0 7003.8 6785.5 6814.4 6818.1 6821.7 6823.2 6704.9 6716.2 6722.9 6726.0 6730.9 6831.7 6988.8 7005.1 7023.3 7024.1 4707.2 4751.0 4754.1 4754.7 4756.2 6770.5 6796.5 6808.9 6811.5 6815.0 6490.9 6564.6 6597.6 6628.2 6638.1 6547.2 6606.7 6613.5 6621.7 6622.8 6493.2 6494.0 6496.2 6501.7 6506.9 6516.2 6609.7 6649.3 6651.9 6657.2 6427.2 6474.1 6476.4 6498.3 6503.5 22:04:41 INFO - 2016-03-02 22:04:41,173 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,173 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14884.60 Median:48166.77 stddev:17504.05 (36.3%) stddev-sans-first:17521.55 22:04:41 INFO - 2016-03-02 22:04:41,174 DEBUG : BROWSER_OUTPUT: Values: 879.2 884.2 889.1 889.1 891.6 14318.0 14894.3 14918.9 14932.2 14958.6 14160.5 14219.6 14244.1 14266.4 14274.2 721.4 723.6 853.9 868.7 877.2 878.2 879.5 886.8 887.1 887.2 919.8 927.5 928.1 928.2 928.6 755.0 762.8 904.3 918.2 918.9 677.7 679.1 680.1 680.1 680.4 14129.9 14270.6 14276.6 14288.6 14301.1 13132.7 13850.9 13893.3 13911.8 14032.1 10890.5 11134.5 11282.9 11329.3 11364.4 3861.7 3951.9 3958.6 3978.5 4011.2 50295.5 52221.0 53265.0 53283.0 53533.2 10609.3 10691.5 10716.6 10817.1 10999.0 19300.0 19332.5 19342.6 19395.0 19428.4 7965.7 8056.2 8096.9 8118.6 8213.4 3483.0 3559.5 4244.7 4471.2 4518.1 3095.1 3130.7 3134.7 3143.1 3146.4 538.4 553.4 554.0 565.8 565.9 617.5 660.6 662.0 668.4 674.9 8573.8 8964.3 8969.1 9015.6 9091.0 524.2 627.6 642.7 648.3 662.6 4267.8 4306.7 4462.0 4495.7 4516.2 59560.7 59860.7 59869.4 63170.7 65384.0 54440.0 54643.7 56361.4 56862.4 56880.4 49782.0 50857.7 50924.2 51134.2 52034.2 8959.4 8986.0 9117.6 9389.5 9479.0 38673.8 39278.6 39827.4 40272.6 40584.2 46551.5 50272.5 50385.7 50627.0 51008.2 10863.3 10911.4 10913.4 10971.6 11280.5 11794.5 12348.9 12648.1 12662.9 14246.3 11655.2 11831.1 11907.3 12372.7 12841.2 10869.2 11689.2 11698.5 11928.3 12231.6 9221.3 11020.5 11249.4 11442.3 11498.5 10219.4 10273.9 10307.5 10373.9 10408.9 9414.4 9739.5 9750.5 9762.2 10042.2 22:04:41 INFO - 2016-03-02 22:04:41,174 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:04:41 INFO - 2016-03-02 22:04:41,174 DEBUG : BROWSER_OUTPUT: 22:04:41 INFO - 2016-03-02 22:04:41,688 INFO : Browser exited with error code: 0 22:04:41 INFO - 2016-03-02 22:04:41,704 INFO : Completed test dromaeo_css (00:18:34) 22:04:41 INFO - 2016-03-02 22:04:41,704 INFO : Starting test kraken 22:04:41 INFO - 2016-03-02 22:04:41,705 DEBUG : operating with platform_type : linux_ 22:04:41 INFO - 2016-03-02 22:04:41,705 INFO : Initialising browser for kraken test... 22:04:41 INFO - 2016-03-02 22:04:41,711 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUQsIcP/profile http://localhost:48196/getInfo.html 22:04:45 INFO - 2016-03-02 22:04:45,731 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:04:45 INFO - 2016-03-02 22:04:45,731 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:04:45 INFO - 2016-03-02 22:04:45,731 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:04:45 INFO - 2016-03-02 22:04:45,731 DEBUG : BROWSER_OUTPUT: __metrics 22:04:46 INFO - 2016-03-02 22:04:46,062 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:04:47 INFO - 2016-03-02 22:04:47,128 INFO : Browser initialized. 22:04:47 INFO - 2016-03-02 22:04:47,129 INFO : Running cycle 1/1 for kraken test... 22:04:47 INFO - 2016-03-02 22:04:47,129 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUQsIcP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:04:58 INFO - 2016-03-02 22:04:58,826 DEBUG : BROWSER_OUTPUT: RSS: Main: 132444160 22:04:58 INFO - 2016-03-02 22:04:58,826 DEBUG : BROWSER_OUTPUT: 22:05:01 INFO - 2016-03-02 22:05:01,252 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48196/tests/kraken/driver.html?testName=audio-beat-detection) 22:05:01 INFO - 2016-03-02 22:05:01,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 164831232 22:05:01 INFO - 2016-03-02 22:05:01,826 DEBUG : BROWSER_OUTPUT: 22:05:04 INFO - 2016-03-02 22:05:04,353 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48196/tests/kraken/driver.html?testName=audio-dft) 22:05:04 INFO - 2016-03-02 22:05:04,919 DEBUG : BROWSER_OUTPUT: RSS: Main: 163471360 22:05:04 INFO - 2016-03-02 22:05:04,919 DEBUG : BROWSER_OUTPUT: 22:05:07 INFO - 2016-03-02 22:05:07,685 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48196/tests/kraken/driver.html?testName=audio-fft) 22:05:08 INFO - 2016-03-02 22:05:08,258 DEBUG : BROWSER_OUTPUT: RSS: Main: 158531584 22:05:08 INFO - 2016-03-02 22:05:08,258 DEBUG : BROWSER_OUTPUT: 22:05:10 INFO - 2016-03-02 22:05:10,250 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48196/tests/kraken/driver.html?testName=audio-oscillator) 22:05:10 INFO - 2016-03-02 22:05:10,817 DEBUG : BROWSER_OUTPUT: RSS: Main: 159670272 22:05:10 INFO - 2016-03-02 22:05:10,817 DEBUG : BROWSER_OUTPUT: 22:05:13 INFO - 2016-03-02 22:05:13,003 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48196/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:05:13 INFO - 2016-03-02 22:05:13,564 DEBUG : BROWSER_OUTPUT: RSS: Main: 158494720 22:05:13 INFO - 2016-03-02 22:05:13,565 DEBUG : BROWSER_OUTPUT: 22:05:16 INFO - 2016-03-02 22:05:16,427 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48196/tests/kraken/driver.html?testName=imaging-darkroom) 22:05:16 INFO - 2016-03-02 22:05:16,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 159203328 22:05:16 INFO - 2016-03-02 22:05:16,994 DEBUG : BROWSER_OUTPUT: 22:05:20 INFO - 2016-03-02 22:05:20,347 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48196/tests/kraken/driver.html?testName=imaging-desaturate) 22:05:20 INFO - 2016-03-02 22:05:20,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 158801920 22:05:20 INFO - 2016-03-02 22:05:20,922 DEBUG : BROWSER_OUTPUT: 22:05:23 INFO - 2016-03-02 22:05:23,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48196/tests/kraken/driver.html?testName=json-parse-financial) 22:05:24 INFO - 2016-03-02 22:05:24,208 DEBUG : BROWSER_OUTPUT: RSS: Main: 160157696 22:05:24 INFO - 2016-03-02 22:05:24,208 DEBUG : BROWSER_OUTPUT: 22:05:25 INFO - 2016-03-02 22:05:25,742 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48196/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:05:26 INFO - 2016-03-02 22:05:26,303 DEBUG : BROWSER_OUTPUT: RSS: Main: 166232064 22:05:26 INFO - 2016-03-02 22:05:26,303 DEBUG : BROWSER_OUTPUT: 22:05:28 INFO - 2016-03-02 22:05:28,040 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:05:28 INFO - 2016-03-02 22:05:28,642 DEBUG : BROWSER_OUTPUT: RSS: Main: 163463168 22:05:28 INFO - 2016-03-02 22:05:28,642 DEBUG : BROWSER_OUTPUT: 22:05:30 INFO - 2016-03-02 22:05:30,594 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:05:31 INFO - 2016-03-02 22:05:31,160 DEBUG : BROWSER_OUTPUT: RSS: Main: 168464384 22:05:31 INFO - 2016-03-02 22:05:31,160 DEBUG : BROWSER_OUTPUT: 22:05:33 INFO - 2016-03-02 22:05:33,514 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:05:34 INFO - 2016-03-02 22:05:34,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 175259648 22:05:34 INFO - 2016-03-02 22:05:34,096 DEBUG : BROWSER_OUTPUT: 22:05:36 INFO - 2016-03-02 22:05:36,615 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:05:37 INFO - 2016-03-02 22:05:37,183 DEBUG : BROWSER_OUTPUT: RSS: Main: 174039040 22:05:37 INFO - 2016-03-02 22:05:37,183 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48196/tests/kraken/driver.html?testName=ai-astar) 22:05:39 INFO - 2016-03-02 22:05:39,280 DEBUG : BROWSER_OUTPUT: RSS: Main: 232591360 22:05:39 INFO - 2016-03-02 22:05:39,280 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,281 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:05:39 INFO - 2016-03-02 22:05:39,281 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:05:39 INFO - 2016-03-02 22:05:39,282 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:05:39 INFO - 2016-03-02 22:05:39,282 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:05:39 INFO - 2016-03-02 22:05:39,282 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;105;110;109;109;99;108;108;98 22:05:39 INFO - 2016-03-02 22:05:39,282 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;121;147;154;129;148;141;130;158;123 22:05:39 INFO - 2016-03-02 22:05:39,282 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;157;160;161;160;159;161;162;158;159 22:05:39 INFO - 2016-03-02 22:05:39,283 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;71;158;71;71;71;71;154;71 22:05:39 INFO - 2016-03-02 22:05:39,283 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;135;92;93;93;146;92;92;133 22:05:39 INFO - 2016-03-02 22:05:39,283 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;108;109;110;109;109;109;109;109;108;109 22:05:39 INFO - 2016-03-02 22:05:39,283 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;145;146;146;145;145;146;145 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;96;95;95;95;96;95;95;96 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;57;57;56;58;56;57;55;55 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;65;64;64;64;64;64;64;64 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;76;86;79;76;78;83;77;80;77 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;117;115;116;119;117;116;127;121;117 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;141;143;140;142;130;149;139;141;140 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;66;65;64;64;62;63;62;62;63 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,889 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: __startTimestamp1456985139280__endTimestamp 22:05:39 INFO - 2016-03-02 22:05:39,284 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.30 Median:109.50 stddev:4.37 (4.0%) stddev-sans-first:4.63 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 105.0 110.0 109.0 109.0 99.0 108.0 108.0 98.0 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.70 Median:146.50 stddev:13.08 (8.9%) stddev-sans-first:13.67 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: Values: 146.0 121.0 147.0 154.0 129.0 148.0 141.0 130.0 158.0 123.0 22:05:39 INFO - 2016-03-02 22:05:39,286 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.80 Median:160.50 stddev:1.55 (1.0%) stddev-sans-first:1.58 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: Values: 161.0 157.0 160.0 161.0 160.0 159.0 161.0 162.0 158.0 159.0 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.00 Median:71.00 stddev:35.85 (50.5%) stddev-sans-first:37.49 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 158.0 71.0 71.0 71.0 71.0 154.0 71.0 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:92.00 stddev:22.33 (24.3%) stddev-sans-first:23.10 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 135.0 92.0 93.0 93.0 146.0 92.0 92.0 133.0 22:05:39 INFO - 2016-03-02 22:05:39,287 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,288 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 22:05:39 INFO - 2016-03-02 22:05:39,288 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 108.0 109.0 22:05:39 INFO - 2016-03-02 22:05:39,288 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 146.0 145.0 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.50 stddev:0.95 (1.7%) stddev-sans-first:1.00 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 57.0 57.0 56.0 58.0 56.0 57.0 55.0 55.0 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:78.50 stddev:3.36 (4.3%) stddev-sans-first:3.41 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 86.0 79.0 76.0 78.0 83.0 77.0 80.0 77.0 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,290 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.10 Median:117.00 stddev:3.57 (3.1%) stddev-sans-first:3.71 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: Values: 116.0 117.0 115.0 116.0 119.0 117.0 116.0 127.0 121.0 117.0 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.90 Median:141.00 stddev:5.09 (3.6%) stddev-sans-first:4.93 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: Values: 134.0 141.0 143.0 140.0 142.0 130.0 149.0 139.0 141.0 140.0 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:64.00 stddev:1.35 (2.1%) stddev-sans-first:1.42 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: Values: 64.0 66.0 65.0 64.0 64.0 62.0 63.0 62.0 62.0 63.0 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:05:39 INFO - 2016-03-02 22:05:39,291 DEBUG : BROWSER_OUTPUT: 22:05:40 INFO - 2016-03-02 22:05:40,008 INFO : Browser exited with error code: 0 22:05:40 INFO - 2016-03-02 22:05:40,064 INFO : Completed test kraken (00:00:58) 22:05:40 INFO - 2016-03-02 22:05:40,064 INFO : Starting test v8_7 22:05:40 INFO - 2016-03-02 22:05:40,064 DEBUG : operating with platform_type : linux_ 22:05:40 INFO - 2016-03-02 22:05:40,064 INFO : Initialising browser for v8_7 test... 22:05:40 INFO - 2016-03-02 22:05:40,070 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7TGJx7/profile http://localhost:48196/getInfo.html 22:05:44 INFO - 2016-03-02 22:05:44,129 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:05:44 INFO - 2016-03-02 22:05:44,129 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:05:44 INFO - 2016-03-02 22:05:44,129 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 22:05:44 INFO - 2016-03-02 22:05:44,129 DEBUG : BROWSER_OUTPUT: __metrics 22:05:44 INFO - 2016-03-02 22:05:44,311 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:05:45 INFO - 2016-03-02 22:05:45,636 INFO : Browser initialized. 22:05:45 INFO - 2016-03-02 22:05:45,637 INFO : Running cycle 1/1 for v8_7 test... 22:05:45 INFO - 2016-03-02 22:05:45,637 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7TGJx7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:05:57 INFO - 2016-03-02 22:05:57,292 DEBUG : BROWSER_OUTPUT: RSS: Main: 131702784 22:05:57 INFO - 2016-03-02 22:05:57,292 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48196/tests/v8_7/run.html (next: http://localhost:48196/tests/v8_7/run.html) 22:06:18 INFO - 2016-03-02 22:06:18,916 DEBUG : BROWSER_OUTPUT: RSS: Main: 423002112 22:06:18 INFO - 2016-03-02 22:06:18,916 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,916 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:06:18 INFO - 2016-03-02 22:06:18,916 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:06:18 INFO - 2016-03-02 22:06:18,916 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:06:18 INFO - 2016-03-02 22:06:18,916 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:06:18 INFO - 2016-03-02 22:06:18,917 DEBUG : BROWSER_OUTPUT: |0;Richards;172.0578114246387 22:06:18 INFO - 2016-03-02 22:06:18,917 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.74464579901152 22:06:18 INFO - 2016-03-02 22:06:18,917 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.4694783573807 22:06:18 INFO - 2016-03-02 22:06:18,917 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5143.589743589743 22:06:18 INFO - 2016-03-02 22:06:18,917 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.9377990430622 22:06:18 INFO - 2016-03-02 22:06:18,918 DEBUG : BROWSER_OUTPUT: |5;Earley;542.2993492407809 22:06:18 INFO - 2016-03-02 22:06:18,918 DEBUG : BROWSER_OUTPUT: |6;Boyer;10578.947368421053 22:06:18 INFO - 2016-03-02 22:06:18,918 DEBUG : BROWSER_OUTPUT: |7;RegExp;24214.285714285714 22:06:18 INFO - 2016-03-02 22:06:18,918 DEBUG : BROWSER_OUTPUT: |8;Splay;522.7391531625718 22:06:18 INFO - 2016-03-02 22:06:18,918 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 22:06:18 INFO - 2016-03-02 22:06:18,919 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:06:18 INFO - 2016-03-02 22:06:18,919 DEBUG : BROWSER_OUTPUT: __startTimestamp1456985178908__endTimestamp 22:06:18 INFO - 2016-03-02 22:06:18,919 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,919 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:06:18 INFO - 2016-03-02 22:06:18,919 DEBUG : BROWSER_OUTPUT: Number of tests: 10 22:06:18 INFO - 2016-03-02 22:06:18,920 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,920 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:172.06 Median:172.06 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,920 DEBUG : BROWSER_OUTPUT: Values: 172.1 22:06:18 INFO - 2016-03-02 22:06:18,920 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,921 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,921 DEBUG : BROWSER_OUTPUT: Values: 164.7 22:06:18 INFO - 2016-03-02 22:06:18,921 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,921 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.47 Median:277.47 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,921 DEBUG : BROWSER_OUTPUT: Values: 277.5 22:06:18 INFO - 2016-03-02 22:06:18,922 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,922 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5143.59 Median:5143.59 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,922 DEBUG : BROWSER_OUTPUT: Values: 5143.6 22:06:18 INFO - 2016-03-02 22:06:18,922 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,922 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.94 Median:956.94 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,922 DEBUG : BROWSER_OUTPUT: Values: 956.9 22:06:18 INFO - 2016-03-02 22:06:18,923 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,923 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.30 Median:542.30 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,923 DEBUG : BROWSER_OUTPUT: Values: 542.3 22:06:18 INFO - 2016-03-02 22:06:18,923 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,924 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10578.95 Median:10578.95 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,924 DEBUG : BROWSER_OUTPUT: Values: 10578.9 22:06:18 INFO - 2016-03-02 22:06:18,924 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,924 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24214.29 Median:24214.29 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,924 DEBUG : BROWSER_OUTPUT: Values: 24214.3 22:06:18 INFO - 2016-03-02 22:06:18,925 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,925 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:522.74 Median:522.74 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,925 DEBUG : BROWSER_OUTPUT: Values: 522.7 22:06:18 INFO - 2016-03-02 22:06:18,925 DEBUG : BROWSER_OUTPUT: 22:06:18 INFO - 2016-03-02 22:06:18,925 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 22:06:18 INFO - 2016-03-02 22:06:18,925 DEBUG : BROWSER_OUTPUT: Values: 4835.7 22:06:18 INFO - 2016-03-02 22:06:18,926 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:06:18 INFO - 2016-03-02 22:06:18,926 DEBUG : BROWSER_OUTPUT: 22:06:19 INFO - 2016-03-02 22:06:19,628 INFO : Browser exited with error code: 0 22:06:19 INFO - 2016-03-02 22:06:19,645 INFO : Completed test v8_7 (00:00:39) 22:06:19 INFO - 2016-03-02 22:06:19,972 INFO : Completed test suite (00:20:12) 22:06:19 INFO - 2016-03-02 22:06:19,972 DEBUG : Working with test: dromaeo_css 22:06:19 INFO - 2016-03-02 22:06:19,972 DEBUG : Generating results file: dromaeo_css 22:06:19 INFO - 2016-03-02 22:06:19,974 DEBUG : Working with test: kraken 22:06:19 INFO - 2016-03-02 22:06:19,974 DEBUG : Generating results file: kraken 22:06:19 INFO - 2016-03-02 22:06:19,974 INFO : javascript benchmark 22:06:19 INFO - 2016-03-02 22:06:19,974 DEBUG : Working with test: v8_7 22:06:19 INFO - 2016-03-02 22:06:19,975 DEBUG : Generating results file: v8_7 22:06:19 INFO - 2016-03-02 22:06:19,975 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:06:20 INFO - 2016-03-02 22:06:20,045 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 22:06:20 INFO - 2016-03-02 22:06:20,045 DEBUG : process_Request line: dromaeo_css 7298.85 graph.html#tests=[[72,53,35]] 22:06:20 INFO - 2016-03-02 22:06:20,046 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:06:20 INFO - 2016-03-02 22:06:20,075 DEBUG : process_Request line: kraken 1470.40 graph.html#tests=[[232,53,35]] 22:06:20 INFO - 2016-03-02 22:06:20,075 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:06:20 INFO - 2016-03-02 22:06:20,107 DEBUG : process_Request line: v8_7 23230.53 graph.html#tests=[[230,53,35]] 22:06:20 INFO - 2016-03-02 22:06:20,109 INFO : javascript benchmark 22:06:20 INFO - 2016-03-02 22:06:20,112 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3272.0, 3346.531485592019, 3375.4599264117733, 3381.0026042317513, 3392.864285428583, 5491.368492623349, 5507.64200326979, 5517.724113794305, 5533.418991005947, 5557.805476808313, 5531.115021596545, 5532.695701736403, 5545.195946587753, 5573.470520300575, 5577.135543990677, 4974.303597496353, 5045.394552653677, 5078.746062696869, 5081.364829396325, 5138.845834624948, 5022.372203474566, 5092.159793634046, 5092.898142037157, 5094.414142373619, 5101.209312556541, 4940.1848694965465, 4993.226049962262, 5058.721770302632, 5065.113605119108, 5096.057229412552, 5073.46728593496, 5089.440161582242, 5090.669106508065, 5091.312672789181, 5105.566026887682, 4761.904761904742, 4810.8316208932865, 5007.39911210657, 5008.223725322581, 5063.8480845574695, 5129.3075434816565, 5264.473552644743, 5282.709450980195, 5284.128118860383, 5284.365876094893, 5873.412658734135, 5903.35063143054, 5964.850878728023, 5970.1343305220935, 5970.492508136814, 5683.772649094031, 5817.592768506164, 5927.673977931212, 5969.343372229051, 5988.28140623128, 5618.438156184389, 5630.859228519279, 5631.408702086263, 5634.549236061105, 5635.69003704792, 6046.818595442144, 6062.090686397076, 6063.393660633901, 6064.545159112996, 6069.423404776539, 5668.0, 5713.0, 5732.71336433185, 5841.561882858802, 5845.210896528999, 5918.822435326947, 5923.259592550931, 5929.822105336847, 5929.911051334234, 5947.821565353046, 5893.675847828325, 5900.557458190653, 5908.113782932594, 5909.704514774244, 5915.438033386821, 5680.40355762639, 5768.682722450221, 5788.479036886701, 5844.357120716718, 5882.205902203234, 4462.509123996358, 4477.395551600557, 4478.261086920622, 4493.415855938707, 4497.752623605667, 3954.307996100671, 4039.434479172918, 4041.7372870763306, 4057.5739547347102, 4073.1853629274265, 5526.613137080446, 5544.805931792449, 5631.239782629293, 5669.801556945569, 5691.089425691952, 5939.673317967553, 5960.284765828128, 5965.224520812267, 5966.0, 5972.074328479092, 5429.294191755047, 5617.101263797773, 5630.8029218977135, 5636.464535869086, 5648.548116150698, 4820.25286080658, 4911.45973942866, 5017.799288028438, 5018.824341148043, 5035.345405097314, 6057.8485537861025, 6069.514438844882, 6073.301570319381, 6074.483668888106, 6079.939200608026, 5666.914996275142, 5827.358990510956, 5976.282846058501, 5978.581499295007, 5978.790742323998, 5955.880882382415, 6040.546958978006, 6046.486048685911, 6046.637201767908, 6086.421789929949, 5846.853828654148, 5848.122781582797, 5900.409959004065, 5902.1146827976145, 5915.763369465173, 5734.827955161351, 5821.359650438533, 5825.825225243249, 6007.038873780174, 6022.518198544019, 5836.533077353886, 5848.64908105515, 5852.7658893643775, 5872.29532456108, 5875.529957603467, 5826.242588463472, 5867.0, 5876.588638795243, 5882.411758824083, 5882.676452795137, 5554.527865131509, 5604.747786349542, 5635.351934527418, 5719.771209151588, 5781.479666829838, 5842.474177324061, 5868.471837534642, 5887.1757953885635, 5897.0, 5906.143609176559, 5715.8856822864145, 5757.568182386254, 5768.971155144198, 5773.1051686988585, 5773.566982476247], "unit": "ms", "name": "dojo.html", "value": 5450.826050379722}, {"lowerIsBetter": false, "replicates": [611.2786911444493, 641.4547634510669, 647.8801421736978, 657.4707360574736, 694.5069001009283, 32666.346673066524, 40636.0, 41271.0, 41329.0, 41367.79316103423, 45724.77137614316, 46145.0, 46163.0, 46188.538114618845, 46358.07283854321, 8059.274665280123, 8183.754487365389, 9131.178193962542, 9297.256219502424, 9302.302327325444, 10455.163586913066, 10618.362898226093, 10619.044286014258, 10628.574857005711, 10628.627998020032, 10823.621173258938, 10916.890831091707, 10929.071028962548, 10929.781404371908, 10931.01620854123, 10738.140948724084, 10798.028177464066, 10801.67594972152, 10814.243002989793, 10832.566697332097, 10693.572257109707, 10818.459077046195, 10819.188560857967, 10821.83767243492, 10826.18803589734, 43266.567334326566, 43641.34537981933, 43694.78152609248, 43714.0, 43821.34267985983, 3763.0216143802536, 3837.9424308635394, 4010.3382941814566, 4147.730397524181, 4152.875413737593, 32916.177095572566, 33081.17297067568, 33322.166945826306, 33567.0, 33692.0, 4220.53574106848, 4270.274053410896, 4285.807138678767, 4304.375865499485, 4334.761588112652, 309452.0, 309994.0, 310051.0, 310228.0, 310416.0, 57912.0, 59509.702451487465, 64656.35343646597, 65539.0, 65550.67224663847, 58437.70781146067, 58845.117323240185, 58877.116843247386, 58917.70541147267, 59004.7049764757, 16400.835991640168, 16471.83528164703, 16557.58606034859, 16566.751498727528, 16568.83431165697, 12119.212251203644, 12162.574309899288, 12174.208676436003, 12176.512939482498, 12191.146619736533, 20504.69242961357, 21474.14103435845, 21659.458513537287, 21693.240736574146, 21695.132194712034, 7962.522248665098, 8089.878651820228, 8116.837663246702, 8168.183181681784, 8227.13615070421, 983.3804703037184, 1045.5713157605346, 1072.8712554493354, 1077.7305673581604, 1100.5597760895705, 29598.260043499085, 29732.0, 30740.53889191664, 30808.845955770077, 30919.536206956913, 1106.4744246482855, 1138.9829152562552, 1207.6558180918396, 1241.1002023532867, 1278.0733967873218, 13479.989000824977, 15109.266584527513, 15576.221188940508, 16025.358985640676, 16572.337106515846, 387541.0, 397215.0, 398234.0, 398626.0, 399799.0, 57109.71445142748, 57187.0, 57443.0, 57551.13673294904, 57693.423065769646, 1340.9597712068653, 1355.5255660518937, 1475.8007668964767, 1500.3698446652431, 1576.8501992310712, 26878.193654190407, 27009.0, 27417.72582274152, 27466.725332746417, 28010.85994570014, 46541.3018804725, 47233.52766472291, 47242.29136562886, 47294.52705472901, 47420.52579474161, 253340.0, 254840.0, 255329.0, 256702.0, 257731.0, 34670.133246668025, 35247.0, 35275.29449411049, 35523.11192220215, 35536.46695299624, 40317.79841100776, 40545.391819122146, 40634.0, 41185.38221926733, 41789.0, 35309.646903530636, 35318.0, 35442.468362975094, 36041.459378109874, 39014.804925975186, 37532.0, 37536.624633753316, 38181.61818381781, 38441.231175376895, 38529.22941541098, 37397.81301093477, 38245.42631860469, 38318.616813831504, 38332.80833595814, 38391.0, 32656.34687306288, 34173.82913085419, 35605.28789424145, 35630.465543016355, 36049.63950360463, 25938.0, 26381.736182637927, 26424.339391514597, 26617.86691066532, 26935.191944241706], "unit": "ms", "name": "ext.html", "value": 19943.014789602188}, {"lowerIsBetter": false, "replicates": [363.35867194401885, 363.38043635601304, 378.7840930669519, 381.555487856647, 388.10928918132885, 15349.923250383761, 17091.572710682223, 17127.0, 18415.0, 18973.241070357188, 10828.133749300057, 10849.511771970261, 13646.590602281947, 17139.31442742292, 17474.475765727017, 5011.924821127677, 5135.537801597856, 5136.8972620547565, 5137.2037334213255, 5141.640085194038, 4093.9590604094024, 4206.64243539298, 5441.972790136064, 5568.443155684439, 5577.972110139443, 5514.200440936062, 5536.390996990328, 5539.335279766434, 5541.806036788713, 5555.722213889309, 5268.5521730652945, 5419.810306639249, 5424.0, 5429.619926605139, 5442.346918369801, 5236.92144617831, 5280.867978300535, 5609.0, 5612.15817627355, 5616.129499927518, 19766.604667906562, 19810.50473738168, 19846.404607861787, 20191.0, 20356.18575256988, 2259.9830076465655, 2280.3501002214457, 2305.124052859919, 2315.409570559513, 2334.054707843326, 9840.409575425498, 10070.69787906364, 12626.747465050648, 13248.0, 14125.505607303696, 1959.5199176201922, 1968.0, 2001.4095841726726, 2019.545602239499, 2024.180207016161, 62501.37498625001, 64453.3554664452, 64884.99999999953, 65568.3443165567, 66476.3352366475, 10680.198985076151, 10894.0, 10947.671569852917, 10958.452077396098, 11014.39420831862, 14341.426342946374, 14415.42338306477, 14438.494652687106, 14449.566513004627, 14462.421503139756, 8403.285720713686, 8424.705135320235, 8442.240198382053, 8470.280026197843, 8473.745787626382, 8293.751187464386, 8299.8340033199, 8324.917760691193, 8356.373272004503, 8377.664893404317, 4642.141203877293, 4730.219513780256, 4733.763311834395, 4737.028909073631, 4742.288656701522, 1764.0953032397338, 1776.3782676063533, 1814.8003719590833, 1927.5373910261717, 1952.5509132899228, 990.6631745206664, 1022.4172221833633, 1056.3292309383596, 1068.8396740488834, 1092.0, 17043.14784260782, 17578.208980595904, 17766.911165444348, 18231.72652410215, 18232.817671823377, 886.2511178054533, 978.0122076702581, 1005.4269066631949, 1035.177034257767, 1041.4948749856314, 9281.0, 9413.105754953269, 9453.527323633929, 9464.90535094654, 9596.0, 662949.0, 663851.0, 663988.0, 670904.0, 706062.0, 84585.15414845773, 84629.57685211535, 85151.57424212963, 85328.0, 86850.56574717085, 1204.5242129358853, 1208.323338930202, 1212.8665846756674, 1217.5860207529483, 1230.4155526125019, 15103.39586416531, 15125.319360628579, 15146.697066058838, 15462.536123916301, 15629.765553516934, 34763.65236347604, 35117.29765404729, 35154.999999998974, 35826.64173358233, 35830.82084589561, 50168.24747628861, 51420.74289628528, 51986.740066299426, 52037.0, 55106.44893551013, 10383.221258405802, 10481.056704896597, 10499.212559058255, 10642.73393165177, 10660.25378223517, 14011.0, 16187.352505899633, 17588.560285992953, 18337.449876503728, 18700.15849286758, 13651.317434128452, 14614.70770584561, 15028.248587570797, 15433.074015559103, 15799.763003554726, 14161.858381416467, 14705.264736763333, 15021.399144034553, 15031.624209394853, 15351.769723454381, 15076.228401122578, 15095.245237738289, 15912.0, 15934.601634958755, 16155.111468869325, 9926.652567160114, 9932.25508086882, 9995.300328976902, 10041.04610062043, 10052.748681283027, 10630.0, 10662.413567253565, 12983.35083245853, 15013.6997260052, 15295.770563441334], "unit": "ms", "name": "jquery.html", "value": 9292.832097191194}, {"lowerIsBetter": false, "replicates": [1522.048719550281, 1766.2405165778716, 1802.546470345123, 1818.1909050472536, 1824.9087545622715, 6811.863762724743, 6976.616286104262, 7501.962490187549, 7685.500442471235, 7685.654145563449, 7236.457265705067, 7408.185099639036, 7447.664855081521, 7453.366463850566, 7475.102987641477, 2279.623862062762, 2311.318161142467, 2314.467672435341, 2336.906523739048, 2339.3449834046496, 2007.0265921028363, 2040.7449068866392, 2283.086765293879, 2317.9536409271805, 2342.1685301717816, 2264.094362255095, 2273.9658905116435, 2277.3964899301614, 2289.404754763757, 2297.9195728149516, 2272.261515007621, 2284.657301404786, 2285.417218609244, 2288.0733303012225, 2299.988500057506, 2269.829762767799, 2277.430642339415, 2286.4055345610095, 2286.6798648189265, 2287.1194590082773, 7540.886886696704, 7621.733239336652, 7629.7329593463965, 7657.081150261948, 7670.616469176574, 4635.652326075524, 4727.929081063787, 4731.574158325767, 4733.0533893221145, 4733.763311834395, 3080.8151510909415, 3096.5355196720448, 3144.7641426893074, 3148.763842711806, 3160.5259211118514, 1035.8498017787454, 1054.8259537176357, 1070.5343175718588, 1075.8493810866407, 1114.8662160540787, 10374.325668831501, 10377.740556486147, 10384.428856412895, 10390.84413733787, 10402.635907743268, 4222.408862759216, 4233.216854881856, 4250.957490425118, 4296.5273819879785, 4299.828006879752, 8992.145746154105, 8995.415298005608, 8997.595108220146, 9002.63989440415, 9004.459732415946, 4756.262779269149, 4770.189067858472, 4773.522647735268, 4781.760911954389, 4786.473487916326, 4326.329418940069, 4367.65058795302, 4367.995361066973, 4372.693911426233, 4375.584319489643, 3694.113412780913, 3746.2132952079824, 3788.677962373229, 3815.561210460777, 3818.1790914952862, 1108.2740804772886, 1133.637236084445, 1176.5411489519095, 1193.659806955031, 1212.1938910624513, 1089.7874914391618, 1103.2994048779083, 1112.5049353037982, 1156.306216270231, 1156.4680247086435, 7396.778096657109, 7449.627518624047, 7468.103827540621, 7478.738244161428, 7495.962520187364, 1109.1570406490964, 1127.3799410324289, 1131.8981291683788, 1141.8344340070644, 1164.126904821384, 5840.2115714378015, 5953.077273022688, 5955.731992060368, 5956.940430595725, 5964.433378829004, 8844.0, 9166.724998250063, 9483.099105584959, 9487.715368538955, 9496.810063798684, 9385.436873787594, 9395.483248421402, 9409.059094090537, 9410.43537387759, 9418.576164072632, 7129.322714342233, 7322.450816188807, 7325.743598974117, 7325.816854578785, 7497.9250207497225, 7455.664495097625, 7473.8878916817375, 7553.5845528494265, 7558.735444259424, 7561.168271490022, 8410.074891762024, 8616.612252448529, 8648.481091134552, 8672.0, 8673.392862499564, 8619.784505387415, 8672.392932494664, 8806.955965220133, 8823.205911467998, 8827.735167944971, 7008.684609192496, 7060.3645671887725, 7090.787276381717, 7095.574265544084, 7109.715611375694, 6669.0, 7533.660985255787, 7541.924580754122, 7573.242675732383, 7576.545407275581, 6578.440832529289, 7009.684564194726, 7208.135023797178, 7289.8177545559665, 7309.817254568678, 7180.712771489082, 7235.927640723526, 7241.674124664298, 7285.854282914418, 7350.7427240046345, 7256.346928776436, 7279.8544029120185, 7289.015982842248, 7289.343959043712, 7291.30732580404, 6763.154605674291, 6817.0, 6877.552959057645, 6898.827529311808, 6900.378965893094, 6850.143732033496, 6920.134983127109, 6922.030915671709, 6923.0, 6943.479239057192], "unit": "ms", "name": "mootools.html", "value": 4547.100882731618}, {"lowerIsBetter": false, "replicates": [353.9522164507791, 354.65952685421996, 360.6339565341178, 361.8317482370697, 362.5957057880464, 6057.454829065383, 6101.572889897703, 6114.633122012677, 6132.662703551303, 6155.415235552616, 5913.793017244398, 6035.0, 6045.214122164123, 6047.939520604793, 6049.485793707529, 4710.599599034069, 4717.221658426372, 4723.480417154119, 4724.0, 4731.33761273422, 4739.668223224376, 4740.288956656512, 4740.976295118537, 4744.857654270378, 4748.90502189956, 4555.180067587833, 4660.137874493213, 4662.417197850269, 4671.3460115583675, 4701.271302948031, 4658.161530924433, 4658.510856360084, 4661.953380466186, 4662.860114196579, 4684.273937539704, 3946.5856085110936, 4038.353863381845, 4123.175364926997, 4124.154548317618, 4131.091159944837, 5671.432856714296, 5737.3688894221605, 5779.450952159538, 5794.449527294901, 5811.012127938218, 5436.809711660073, 5442.564594832404, 5451.918221226685, 5453.045716999509, 5461.371942226655, 6355.872882542323, 6471.352864713491, 6474.093626892239, 6480.805575832733, 6500.089987401768, 4545.840895568639, 4549.840755573539, 4552.089582083597, 4552.385427967249, 4560.0, 6617.602943823386, 6692.464602831762, 6695.163104611972, 6704.865902681919, 6708.060871477997, 4681.7425041622355, 4686.554777296152, 4690.718556886598, 4692.6245900327895, 4693.389859318267, 4639.095376401637, 4748.881277968009, 4755.881102972453, 4756.453007904066, 4763.356946812205, 5424.240606315119, 5427.4301198373605, 5427.782888684487, 5433.130699088206, 5437.891242175135, 6502.967485162639, 6511.804645860631, 6513.674316284261, 6518.511111666644, 6520.0871877937125, 1650.026484374225, 1676.0949087492618, 1677.8238284979902, 1682.5793551612096, 1684.8652107831344, 1089.743910181107, 1097.7145942054963, 1116.3525155409843, 1128.2158899564888, 1136.091127098318, 6343.0, 6354.650494222769, 6355.428011478989, 6356.173697419398, 6356.745730170742, 6680.899786503206, 6711.966440167866, 6716.059751634775, 6731.057651928734, 6731.663416829139, 6269.216347956505, 6350.872982540323, 6353.682315884187, 6357.11000459936, 6359.300476947532, 3646.197836475971, 3679.7240206984584, 3680.7607505512055, 3682.8895133146048, 3684.299983003221, 6679.966600166968, 6801.591904485746, 6810.965945170342, 6881.277465866112, 6953.296442371651, 6967.721291148398, 6982.685779139951, 6987.580745155307, 6988.091548098816, 7004.15950085993, 6796.422304104207, 6986.476014298948, 6989.126359205099, 7001.0, 7003.754868579677, 6785.457163426815, 6814.352636499525, 6818.113645226089, 6821.693023814039, 6823.215330237086, 6704.932950670431, 6716.1604799400075, 6722.932770672426, 6725.9663701681175, 6730.865382692221, 6831.658417079226, 6988.755393561404, 7005.05431766705, 7023.33278338557, 7024.086868707035, 4707.152712511644, 4751.049790041936, 4754.072955773591, 4754.738489383117, 4756.19144745401, 6770.461077096089, 6796.456283497408, 6808.897866531907, 6811.5231933766145, 6815.011823285695, 6490.935090649033, 6564.6389448580785, 6597.60414375139, 6628.171478565179, 6638.137042184679, 6547.181602299713, 6606.669666516751, 6613.470922326299, 6621.735130594722, 6622.768203112868, 6493.155889734304, 6493.967530162319, 6496.155499785186, 6501.739930402731, 6506.934930650633, 6516.185476815398, 6609.735610575523, 6649.301823308483, 6651.933480665131, 6657.167854018247, 6427.196600424947, 6474.0612611172055, 6476.384743449365, 6498.317676643885, 6503.479721622353], "unit": "ms", "name": "prototype.html", "value": 4944.0488735578365}, {"lowerIsBetter": false, "replicates": [879.2306731609841, 884.211775643625, 889.09756597054, 889.1330952321483, 891.5943245823152, 14318.0, 14894.255287235648, 14918.925405372971, 14932.178730169837, 14958.551243462714, 14160.50438234662, 14219.573412797607, 14244.14535127893, 14266.429342826275, 14274.214918179521, 721.3651986252091, 723.5875550935968, 853.9359548033907, 868.7176667583029, 877.1623099939543, 878.2359347367799, 879.5338470610586, 886.7650072730731, 887.1217494680285, 887.2148148888216, 919.8482250428705, 927.4713413354391, 928.067292371166, 928.1739252065667, 928.6099838068028, 755.0184759812249, 762.8169239382536, 904.267543289934, 918.1507105927058, 918.8529835226399, 677.6814896998452, 679.0832376292016, 680.0920770771027, 680.1022650101868, 680.4216416046321, 14129.858701412957, 14270.643233919234, 14276.643083922881, 14288.57134285973, 14301.141931484144, 13132.6716832079, 13850.93074534631, 13893.305334733222, 13911.79132313016, 14032.08791428554, 10890.455477226107, 11134.49894754738, 11282.943585282103, 11329.263597866113, 11364.431778411046, 3861.6524512793703, 3951.8616848410456, 3958.604139586047, 3978.4629075074786, 4011.2378648056774, 50295.49704502945, 52221.0, 53265.0, 53283.0, 53533.197002045, 10609.310394824312, 10691.518881650345, 10716.624918127829, 10817.134629229642, 10999.01008909186, 19300.0, 19332.516687082654, 19342.613147737247, 19395.0, 19428.417147485598, 7965.721199757981, 8056.154103819132, 8096.878546821802, 8118.553479558681, 8213.425060245845, 3483.0247530691445, 3559.4660800879556, 4244.745315281093, 4471.150481408522, 4518.073794872043, 3095.133362658459, 3130.6712795156636, 3134.7335476484755, 3143.0570828751283, 3146.3549972255632, 538.4292649791156, 553.3829779795499, 553.9695316757617, 565.8245943757448, 565.9094544872883, 617.5491890919653, 660.5607271164622, 661.9536632435684, 668.3984414027317, 674.8852695041854, 8573.828523429496, 8964.346687770927, 8969.14793094655, 9015.639374424949, 9090.954545227232, 524.2110623511591, 627.5607075047485, 642.7204166187723, 648.2998361769372, 662.6189940784069, 4267.8292868284925, 4306.698531102793, 4461.955380446219, 4495.685302028892, 4516.16450956574, 59560.70219648874, 59860.70069649711, 59869.40130598638, 63170.68414657897, 65384.0, 54440.0, 54643.72678136584, 56361.43638563562, 56862.43137568654, 56880.43119568834, 49782.0043599126, 50857.74571127195, 50924.236136457985, 51134.23298650523, 52034.21948670773, 8959.41763785352, 8985.955070224607, 9117.635294588143, 9389.530523473662, 9479.0, 38673.80663096667, 39278.6072139275, 39827.40258896061, 40272.597274026884, 40584.188316234104, 46551.53448465472, 50272.497275026784, 50385.74807125941, 50627.0, 51008.234876477625, 10863.293885897392, 10911.399873007062, 10913.39976301311, 10971.561137554409, 11280.548778049115, 11794.46924888365, 12348.876511234772, 12648.114631976041, 12662.936685316514, 14246.287685615885, 11655.242409243372, 11831.05351571855, 11907.285562866256, 12372.690682732644, 12841.165324253894, 10869.239153259196, 11689.240199387013, 11698.532058717556, 11928.284302941851, 12231.571894983988, 9221.308401869752, 11020.50407731638, 11249.381284029458, 11442.256253343507, 11498.48256828428, 10219.386836789816, 10273.9486302568, 10307.484625768831, 10373.948130259301, 10408.947955260175, 9414.388064775767, 9739.464329461947, 9750.463724495221, 9762.219022478042, 10042.196624269895], "unit": "ms", "name": "yui.html", "value": 6656.8443131608055}], "name": "dromaeo_css", "value": 7298.850269573781}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 110.0, 105.0, 110.0, 109.0, 109.0, 99.0, 108.0, 108.0, 98.0], "unit": "ms", "name": "ai-astar", "value": 106.3}, {"lowerIsBetter": true, "replicates": [146.0, 121.0, 147.0, 154.0, 129.0, 148.0, 141.0, 130.0, 158.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.7}, {"lowerIsBetter": true, "replicates": [161.0, 157.0, 160.0, 161.0, 160.0, 159.0, 161.0, 162.0, 158.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.8}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 158.0, 71.0, 71.0, 71.0, 71.0, 154.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.0}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 135.0, 92.0, 93.0, 93.0, 146.0, 92.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 108.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 108.9}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 57.0, 57.0, 56.0, 58.0, 56.0, 57.0, 55.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 86.0, 79.0, 76.0, 78.0, 83.0, 77.0, 80.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.8}, {"lowerIsBetter": true, "replicates": [116.0, 117.0, 115.0, 116.0, 119.0, 117.0, 116.0, 127.0, 121.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.1}, {"lowerIsBetter": true, "replicates": [134.0, 141.0, 143.0, 140.0, 142.0, 130.0, 149.0, 139.0, 141.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.9}, {"lowerIsBetter": true, "replicates": [64.0, 66.0, 65.0, 64.0, 64.0, 62.0, 63.0, 62.0, 62.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.5}], "name": "kraken", "value": 1470.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [172.0578114246387], "unit": "score", "name": "Richards", "value": 205.17522399999996}, {"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "DeltaBlue", "value": 401.33626}, {"lowerIsBetter": false, "replicates": [277.4694783573807], "unit": "score", "name": "Encrypt", "value": 959.316324}, {"lowerIsBetter": false, "replicates": [5143.589743589743], "unit": "score", "name": "Decrypt", "value": 51.75004486540379}, {"lowerIsBetter": false, "replicates": [956.9377990430622], "unit": "score", "name": "RayTrace", "value": 773.2885049999996}, {"lowerIsBetter": false, "replicates": [542.2993492407809], "unit": "score", "name": "Earley", "value": 1228.9577720000002}, {"lowerIsBetter": false, "replicates": [10578.947368421053], "unit": "score", "name": "Boyer", "value": 62.99899004975127}, {"lowerIsBetter": false, "replicates": [24214.285714285714], "unit": "score", "name": "RegExp", "value": 37.621799410029524}, {"lowerIsBetter": false, "replicates": [522.7391531625718], "unit": "score", "name": "Splay", "value": 155.89228299999994}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23230.530121900156}]} 22:06:20 INFO - RETURN: dromaeo_css: 7298.85 22:06:20 INFO - RETURN: kraken: 1470.40 22:06:20 INFO - RETURN: v8_7: 23230.53 22:06:20 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23230.53"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1470.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7298.85"}}} 22:06:20 INFO - Return code: 0 22:06:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:06:20 INFO - # TBPL SUCCESS # 22:06:20 INFO - Running post-action listener: _resource_record_post_action 22:06:20 INFO - Running post-run listener: _resource_record_post_run 22:06:21 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 8216576; Write bytes: 432082944; Read time: 2156; Write time: 562768 22:06:21 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 17002496; Read time: 0; Write time: 19184 22:06:21 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 6455296; Write bytes: 415080448; Read time: 2104; Write time: 543584 22:06:21 INFO - Running post-run listener: _upload_blobber_files 22:06:21 INFO - Blob upload gear active. 22:06:21 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:06:21 INFO - Copying logs to upload dir... 22:06:21 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1270.869430 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-03-02 22:06:21.370632) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-03-02 22:06:21.376574) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009783 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456975040/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-03-02 22:06:21.429357) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:06:21.429686) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1456983680.767862-1979319682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:06:21.478410) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-02 22:06:21.478688) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-02 22:06:21.478967) ========= ========= Total master_lag: 0.24 =========