builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1452815828.27 results: success (0) buildid: 20160114133957 builduid: f2ce88b1e1bc422ba6fe6b2b2697469c revision: db934cef3dff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.273559) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.274037) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.274327) ========= 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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006237 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.297222) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.297543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.311102) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.311476) ========= 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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004482 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.360427) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.360762) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.361116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.361383) ========= 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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-14 15:57:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 208M=0s 2016-01-14 15:57:08 (208 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105348 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.479054) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.479354) ========= 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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022674 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.530703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:08.531050) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev db934cef3dff --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 db934cef3dff --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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-14 15:57:08,824 Setting DEBUG logging. 2016-01-14 15:57:08,824 attempt 1/10 2016-01-14 15:57:08,824 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/db934cef3dff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-14 15:57:09,121 unpacking tar archive at: mozilla-beta-db934cef3dff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663244 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:09.206636) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:09.206960) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:09.215969) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:09.216247) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 15:57:09.216639) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-01-14 15:57:09.216914) ========= /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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:57:09 INFO - MultiFileLogger online at 20160114 15:57:09 in /builds/slave/test-pgo 15:57:09 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 15:57:09 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:57:09 INFO - {'append_to_log': False, 15:57:09 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:57:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:57:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:57:09 INFO - 'branch': 'Mozilla-Beta', 15:57:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:57:09 INFO - 'config_files': ('talos/linux_config.py',), 15:57:09 INFO - 'default_actions': ('clobber', 15:57:09 INFO - 'read-buildbot-config', 15:57:09 INFO - 'download-and-extract', 15:57:09 INFO - 'populate-webroot', 15:57:09 INFO - 'create-virtualenv', 15:57:09 INFO - 'install', 15:57:09 INFO - 'run-tests'), 15:57:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:57:09 INFO - 'download_minidump_stackwalk': True, 15:57:09 INFO - 'download_symbols': 'ondemand', 15:57:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:57:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:57:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:57:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:57:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:57:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:57:09 INFO - 'installer_path': 'installer.exe', 15:57:09 INFO - 'log_level': 'info', 15:57:09 INFO - 'log_name': 'talos', 15:57:09 INFO - 'log_to_console': True, 15:57:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:57:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:57:09 INFO - 'opt_config_files': (), 15:57:09 INFO - 'pip_index': False, 15:57:09 INFO - 'sps_profile': False, 15:57:09 INFO - 'sps_profile_interval': 0, 15:57:09 INFO - 'suite': 'dromaeojs', 15:57:09 INFO - 'system_bits': '32', 15:57:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:57:09 INFO - 'title': 'talos-linux64-ix-046', 15:57:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:57:09 INFO - 'use_talos_json': True, 15:57:09 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:57:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:57:09 INFO - 'work_dir': 'build'} 15:57:09 INFO - ##### 15:57:09 INFO - ##### Running clobber step. 15:57:09 INFO - ##### 15:57:09 INFO - Running pre-action listener: _resource_record_pre_action 15:57:09 INFO - Running main action method: clobber 15:57:09 INFO - rmtree: /builds/slave/test-pgo/build 15:57:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:57:10 INFO - Running post-action listener: _resource_record_post_action 15:57:10 INFO - ##### 15:57:10 INFO - ##### Running read-buildbot-config step. 15:57:10 INFO - ##### 15:57:10 INFO - Running pre-action listener: _resource_record_pre_action 15:57:10 INFO - Running main action method: read_buildbot_config 15:57:10 INFO - Using buildbot properties: 15:57:10 INFO - { 15:57:10 INFO - "properties": { 15:57:10 INFO - "buildnumber": 77, 15:57:10 INFO - "product": "firefox", 15:57:10 INFO - "script_repo_revision": "production", 15:57:10 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:57:10 INFO - "repository": "", 15:57:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:57:10 INFO - "buildid": "20160114133957", 15:57:10 INFO - "slavename": "talos-linux64-ix-046", 15:57:10 INFO - "pgo_build": "True", 15:57:10 INFO - "basedir": "/builds/slave/test-pgo", 15:57:10 INFO - "project": "", 15:57:10 INFO - "platform": "ubuntu64_hw", 15:57:10 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:57:10 INFO - "slavebuilddir": "test", 15:57:10 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:57:10 INFO - "branch": "mozilla-beta", 15:57:10 INFO - "repo_path": "releases/mozilla-beta", 15:57:10 INFO - "stage_platform": "linux64-pgo", 15:57:10 INFO - "builduid": "f2ce88b1e1bc422ba6fe6b2b2697469c", 15:57:10 INFO - "revision": "db934cef3dff" 15:57:10 INFO - }, 15:57:10 INFO - "sourcestamp": { 15:57:10 INFO - "repository": "", 15:57:10 INFO - "hasPatch": false, 15:57:10 INFO - "project": "", 15:57:10 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:57:10 INFO - "changes": [ 15:57:10 INFO - { 15:57:10 INFO - "category": null, 15:57:10 INFO - "files": [ 15:57:10 INFO - { 15:57:10 INFO - "url": null, 15:57:10 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2" 15:57:10 INFO - } 15:57:10 INFO - ], 15:57:10 INFO - "repository": "", 15:57:10 INFO - "rev": "db934cef3dff", 15:57:10 INFO - "who": "sendchange", 15:57:10 INFO - "when": 1452815690, 15:57:10 INFO - "number": 7463541, 15:57:10 INFO - "comments": "Bug 1236643 - Reorder GeckoEditable destruction sequence_ r=esawin a=ritu", 15:57:10 INFO - "project": "", 15:57:10 INFO - "at": "Thu 14 Jan 2016 15:54:50", 15:57:10 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:57:10 INFO - "revlink": "", 15:57:10 INFO - "properties": [ 15:57:10 INFO - [ 15:57:10 INFO - "buildid", 15:57:10 INFO - "20160114133957", 15:57:10 INFO - "Change" 15:57:10 INFO - ], 15:57:10 INFO - [ 15:57:10 INFO - "builduid", 15:57:10 INFO - "f2ce88b1e1bc422ba6fe6b2b2697469c", 15:57:10 INFO - "Change" 15:57:10 INFO - ], 15:57:10 INFO - [ 15:57:10 INFO - "pgo_build", 15:57:10 INFO - "True", 15:57:10 INFO - "Change" 15:57:10 INFO - ] 15:57:10 INFO - ], 15:57:10 INFO - "revision": "db934cef3dff" 15:57:10 INFO - } 15:57:10 INFO - ], 15:57:10 INFO - "revision": "db934cef3dff" 15:57:10 INFO - } 15:57:10 INFO - } 15:57:10 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2. 15:57:10 INFO - Running post-action listener: _resource_record_post_action 15:57:10 INFO - ##### 15:57:10 INFO - ##### Running download-and-extract step. 15:57:10 INFO - ##### 15:57:10 INFO - Running pre-action listener: _resource_record_pre_action 15:57:10 INFO - Running main action method: download_and_extract 15:57:10 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:57:10 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')]} 15:57:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/test_packages.json 15:57:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:57:10 INFO - Downloaded 1270 bytes. 15:57:10 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:57:10 INFO - Using the following test package requirements: 15:57:10 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:57:10 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:57:10 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:57:10 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:57:10 INFO - u'jsshell-linux-x86_64.zip'], 15:57:10 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:57:10 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:57:10 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:57:10 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:57:10 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 15:57:10 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:57:10 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 15:57:10 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:57:10 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:57:10 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:57:10 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:57:10 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:57:10 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:57:10 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')]} 15:57:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:57:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:57:12 INFO - Downloaded 22571732 bytes. 15:57:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:57:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:57:12 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:57:13 INFO - Return code: 0 15:57:13 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:57: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')]} 15:57:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:57:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:57:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:57:15 INFO - Downloaded 22571732 bytes. 15:57:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:57:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:57:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:57:16 INFO - Return code: 0 15:57: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')]} 15:57:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:57:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:57:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:57:17 INFO - Downloaded 11131970 bytes. 15:57:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 15:57:17 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:57:17 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 15:57:17 INFO - Return code: 0 15:57:17 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')]} 15:57:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:57:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:57:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:57:22 INFO - Downloaded 56629699 bytes. 15:57:22 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:57:22 INFO - mkdir: /builds/slave/test-pgo/properties 15:57:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:57:22 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:57:22 INFO - Contents: 15:57:22 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:57:22 INFO - Running post-action listener: _resource_record_post_action 15:57:22 INFO - Running post-action listener: set_extra_try_arguments 15:57:22 INFO - ##### 15:57:22 INFO - ##### Running populate-webroot step. 15:57:22 INFO - ##### 15:57:22 INFO - Running pre-action listener: _resource_record_pre_action 15:57:22 INFO - Running main action method: populate_webroot 15:57:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:57:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:57:22 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 15:57:22 INFO - '../fennec_ids.txt'], 15:57:22 INFO - 'tests': ['tcheck2']}, 15:57:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:57:22 INFO - '--tppagecycles', 15:57:22 INFO - '7'], 15:57:22 INFO - 'tests': ['tsvgm']}}, 15:57:22 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:57:22 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:57:22 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:57:22 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:57:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'tests': ['damp', 'tps']}, 15:57:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['damp', 'tps']}, 15:57:22 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:57:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:57:22 INFO - 'other': {'tests': ['a11yr', 15:57:22 INFO - 'ts_paint', 15:57:22 INFO - 'tpaint', 15:57:22 INFO - 'sessionrestore', 15:57:22 INFO - 'sessionrestore_no_auto_restore']}, 15:57:22 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['a11yr', 15:57:22 INFO - 'ts_paint', 15:57:22 INFO - 'tpaint', 15:57:22 INFO - 'sessionrestore', 15:57:22 INFO - 'sessionrestore_no_auto_restore']}, 15:57:22 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['a11yr', 15:57:22 INFO - 'ts_paint', 15:57:22 INFO - 'tpaint', 15:57:22 INFO - 'sessionrestore', 15:57:22 INFO - 'sessionrestore_no_auto_restore']}, 15:57:22 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['a11yr', 15:57:22 INFO - 'ts_paint', 15:57:22 INFO - 'tpaint', 15:57:22 INFO - 'sessionrestore', 15:57:22 INFO - 'sessionrestore_no_auto_restore']}, 15:57:22 INFO - 'other_l64': {'tests': ['a11yr', 15:57:22 INFO - 'ts_paint', 15:57:22 INFO - 'tpaint', 15:57:22 INFO - 'sessionrestore', 15:57:22 INFO - 'sessionrestore_no_auto_restore']}, 15:57:22 INFO - 'other_nol64': {'tests': ['a11yr', 15:57:22 INFO - 'ts_paint', 15:57:22 INFO - 'tpaint', 15:57:22 INFO - 'sessionrestore', 15:57:22 INFO - 'sessionrestore_no_auto_restore']}, 15:57:22 INFO - 'svgr': {'tests': ['tsvgx', 15:57:22 INFO - 'tsvgr_opacity', 15:57:22 INFO - 'tart', 15:57:22 INFO - 'tscrollx', 15:57:22 INFO - 'cart']}, 15:57:22 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['tsvgx', 15:57:22 INFO - 'tsvgr_opacity', 15:57:22 INFO - 'tart', 15:57:22 INFO - 'tscrollx', 15:57:22 INFO - 'cart']}, 15:57:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'tests': ['tp5o']}, 15:57:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'talos_options': ['--e10s'], 15:57:22 INFO - 'tests': ['tp5o']}, 15:57:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'talos_options': ['--xperf_path', 15:57:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:22 INFO - 'tests': ['tp5n']}, 15:57:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:22 INFO - 'talos_options': ['--e10s', 15:57:22 INFO - '--xperf_path', 15:57:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:22 INFO - 'tests': ['tp5n']}}, 15:57:22 INFO - 'talos.zip': {'path': '', 15:57:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:57:22 INFO - Running post-action listener: _resource_record_post_action 15:57:22 INFO - ##### 15:57:22 INFO - ##### Running create-virtualenv step. 15:57:22 INFO - ##### 15:57:22 INFO - Running pre-action listener: _resource_record_pre_action 15:57:22 INFO - Running main action method: create_virtualenv 15:57:22 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:57:22 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 15:57:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:57:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:57:22 INFO - Using real prefix '/usr' 15:57:22 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:57:23 INFO - Installing distribute.............................................................................................................................................................................................done. 15:57:25 INFO - Installing pip.................done. 15:57:25 INFO - Return code: 0 15:57:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:57:25 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')]} 15:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:25 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')]} 15:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:25 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:25 INFO - Using env: {'DISPLAY': ':0', 15:57:25 INFO - 'HOME': '/home/cltbld', 15:57:25 INFO - 'LANG': 'en_US.UTF-8', 15:57:25 INFO - 'LANGUAGE': 'en_US:en', 15:57:25 INFO - 'LOGNAME': 'cltbld', 15:57:25 INFO - 'MAIL': '/var/mail/cltbld', 15:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:25 INFO - 'MOZ_NO_REMOTE': '1', 15:57:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:25 INFO - 'NO_EM_RESTART': '1', 15:57:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:25 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:25 INFO - 'SHELL': '/bin/bash', 15:57:25 INFO - 'SHLVL': '1', 15:57:25 INFO - 'TERM': 'linux', 15:57:25 INFO - 'TMOUT': '86400', 15:57:25 INFO - 'USER': 'cltbld', 15:57:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:25 INFO - '_': '/tools/buildbot/bin/python'} 15:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:25 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 15:57:25 INFO - Cleaning up... 15:57:25 INFO - Return code: 0 15:57:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:57:25 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')]} 15:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:25 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')]} 15:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:25 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:25 INFO - Using env: {'DISPLAY': ':0', 15:57:25 INFO - 'HOME': '/home/cltbld', 15:57:25 INFO - 'LANG': 'en_US.UTF-8', 15:57:25 INFO - 'LANGUAGE': 'en_US:en', 15:57:25 INFO - 'LOGNAME': 'cltbld', 15:57:25 INFO - 'MAIL': '/var/mail/cltbld', 15:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:25 INFO - 'MOZ_NO_REMOTE': '1', 15:57:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:25 INFO - 'NO_EM_RESTART': '1', 15:57:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:25 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:25 INFO - 'SHELL': '/bin/bash', 15:57:25 INFO - 'SHLVL': '1', 15:57:25 INFO - 'TERM': 'linux', 15:57:25 INFO - 'TMOUT': '86400', 15:57:25 INFO - 'USER': 'cltbld', 15:57:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:25 INFO - '_': '/tools/buildbot/bin/python'} 15:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:25 INFO - Downloading/unpacking psutil>=0.7.1 15:57:25 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 15:57:25 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 15:57:25 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 15:57:25 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 15:57:25 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 15:57:25 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 15:57:27 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 15:57:27 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:57:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:57:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:27 INFO - Installing collected packages: psutil 15:57:27 INFO - Running setup.py install for psutil 15:57:27 INFO - building 'psutil._psutil_linux' extension 15:57:27 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 15:57:27 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 15:57:27 INFO - building 'psutil._psutil_posix' extension 15:57:27 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 15:57:28 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 15:57:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:28 INFO - Successfully installed psutil 15:57:28 INFO - Cleaning up... 15:57:28 INFO - Return code: 0 15:57:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:57: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')]} 15:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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')]} 15:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:28 INFO - Using env: {'DISPLAY': ':0', 15:57:28 INFO - 'HOME': '/home/cltbld', 15:57:28 INFO - 'LANG': 'en_US.UTF-8', 15:57:28 INFO - 'LANGUAGE': 'en_US:en', 15:57:28 INFO - 'LOGNAME': 'cltbld', 15:57:28 INFO - 'MAIL': '/var/mail/cltbld', 15:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:28 INFO - 'MOZ_NO_REMOTE': '1', 15:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:28 INFO - 'NO_EM_RESTART': '1', 15:57:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:28 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:28 INFO - 'SHELL': '/bin/bash', 15:57:28 INFO - 'SHLVL': '1', 15:57:28 INFO - 'TERM': 'linux', 15:57:28 INFO - 'TMOUT': '86400', 15:57:28 INFO - 'USER': 'cltbld', 15:57:28 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:28 INFO - '_': '/tools/buildbot/bin/python'} 15:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:57:28 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 15:57:28 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 15:57:28 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 15:57:28 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 15:57:28 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 15:57:28 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 15:57:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:57:30 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:57:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:57:30 INFO - Installing collected packages: mozsystemmonitor 15:57:30 INFO - Running setup.py install for mozsystemmonitor 15:57:30 INFO - Successfully installed mozsystemmonitor 15:57:30 INFO - Cleaning up... 15:57:30 INFO - Return code: 0 15:57:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:57:30 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')]} 15:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:30 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')]} 15:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:30 INFO - Using env: {'DISPLAY': ':0', 15:57:30 INFO - 'HOME': '/home/cltbld', 15:57:30 INFO - 'LANG': 'en_US.UTF-8', 15:57:30 INFO - 'LANGUAGE': 'en_US:en', 15:57:30 INFO - 'LOGNAME': 'cltbld', 15:57:30 INFO - 'MAIL': '/var/mail/cltbld', 15:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:30 INFO - 'MOZ_NO_REMOTE': '1', 15:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:30 INFO - 'NO_EM_RESTART': '1', 15:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:30 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:30 INFO - 'SHELL': '/bin/bash', 15:57:30 INFO - 'SHLVL': '1', 15:57:30 INFO - 'TERM': 'linux', 15:57:30 INFO - 'TMOUT': '86400', 15:57:30 INFO - 'USER': 'cltbld', 15:57:30 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:30 INFO - '_': '/tools/buildbot/bin/python'} 15:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:30 INFO - Downloading/unpacking blobuploader==1.2.4 15:57:30 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 15:57:30 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 15:57:30 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 15:57:30 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 15:57:30 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 15:57:30 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 15:57:32 INFO - Downloading blobuploader-1.2.4.tar.gz 15:57:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:57:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:57:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:57:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:57:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57:32 INFO - Downloading docopt-0.6.1.tar.gz 15:57:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:57:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:57:33 INFO - Installing collected packages: blobuploader, requests, docopt 15:57:33 INFO - Running setup.py install for blobuploader 15:57:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:57:33 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:57:33 INFO - Running setup.py install for requests 15:57:33 INFO - Running setup.py install for docopt 15:57:33 INFO - Successfully installed blobuploader requests docopt 15:57:33 INFO - Cleaning up... 15:57:33 INFO - Return code: 0 15:57:33 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:57:33 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')]} 15:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:33 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')]} 15:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:33 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:33 INFO - Using env: {'DISPLAY': ':0', 15:57:33 INFO - 'HOME': '/home/cltbld', 15:57:33 INFO - 'LANG': 'en_US.UTF-8', 15:57:33 INFO - 'LANGUAGE': 'en_US:en', 15:57:33 INFO - 'LOGNAME': 'cltbld', 15:57:33 INFO - 'MAIL': '/var/mail/cltbld', 15:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:33 INFO - 'MOZ_NO_REMOTE': '1', 15:57:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:33 INFO - 'NO_EM_RESTART': '1', 15:57:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:33 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:33 INFO - 'SHELL': '/bin/bash', 15:57:33 INFO - 'SHLVL': '1', 15:57:33 INFO - 'TERM': 'linux', 15:57:33 INFO - 'TMOUT': '86400', 15:57:33 INFO - 'USER': 'cltbld', 15:57:33 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:33 INFO - '_': '/tools/buildbot/bin/python'} 15:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:57:33 INFO - Running setup.py (path:/tmp/pip-03N1aJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:57:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:57:33 INFO - Running setup.py (path:/tmp/pip-oTF_Hf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:57:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:57:33 INFO - Running setup.py (path:/tmp/pip-3drzw9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:57:34 INFO - Running setup.py (path:/tmp/pip-W1rlUd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:57:34 INFO - Running setup.py (path:/tmp/pip-77YRtr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:57:34 INFO - Running setup.py (path:/tmp/pip-4AW2zW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:57:34 INFO - Running setup.py (path:/tmp/pip-aZCXRR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:57:34 INFO - Running setup.py (path:/tmp/pip-wolGAj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:57:34 INFO - Running setup.py (path:/tmp/pip-P7cvWp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:57:34 INFO - Running setup.py (path:/tmp/pip-pxU2LE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:57:34 INFO - Running setup.py (path:/tmp/pip-uLZHJj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:57:34 INFO - Running setup.py (path:/tmp/pip-SqGz57-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:57:34 INFO - Running setup.py (path:/tmp/pip-dWdZ0N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:57:34 INFO - Running setup.py (path:/tmp/pip-KuC2IL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:57:34 INFO - Running setup.py (path:/tmp/pip-zPUTIW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:57:34 INFO - Running setup.py (path:/tmp/pip-uM4kGj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:57:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:57:34 INFO - Running setup.py (path:/tmp/pip-FTZfAT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:57:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:57:35 INFO - Running setup.py install for manifestparser 15:57:35 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Running setup.py install for mozcrash 15:57:35 INFO - Running setup.py install for mozdebug 15:57:35 INFO - Running setup.py install for mozdevice 15:57:35 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Running setup.py install for mozfile 15:57:35 INFO - Running setup.py install for mozhttpd 15:57:35 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Running setup.py install for mozinfo 15:57:35 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Running setup.py install for mozInstall 15:57:35 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:57:35 INFO - Running setup.py install for mozleak 15:57:35 INFO - Running setup.py install for mozlog 15:57:36 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:57:36 INFO - Running setup.py install for moznetwork 15:57:36 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:57:36 INFO - Running setup.py install for mozprocess 15:57:36 INFO - Running setup.py install for mozprofile 15:57:36 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:57:36 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:57:36 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:57:36 INFO - Running setup.py install for mozrunner 15:57:36 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:57:36 INFO - Running setup.py install for mozscreenshot 15:57:36 INFO - Running setup.py install for moztest 15:57:36 INFO - Running setup.py install for mozversion 15:57:36 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:57:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:57:36 INFO - Cleaning up... 15:57:36 INFO - Return code: 0 15:57:36 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:57:36 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')]} 15:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:36 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')]} 15:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:36 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:36 INFO - Using env: {'DISPLAY': ':0', 15:57:36 INFO - 'HOME': '/home/cltbld', 15:57:36 INFO - 'LANG': 'en_US.UTF-8', 15:57:36 INFO - 'LANGUAGE': 'en_US:en', 15:57:36 INFO - 'LOGNAME': 'cltbld', 15:57:36 INFO - 'MAIL': '/var/mail/cltbld', 15:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:36 INFO - 'MOZ_NO_REMOTE': '1', 15:57:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:36 INFO - 'NO_EM_RESTART': '1', 15:57:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:36 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:36 INFO - 'SHELL': '/bin/bash', 15:57:36 INFO - 'SHLVL': '1', 15:57:36 INFO - 'TERM': 'linux', 15:57:36 INFO - 'TMOUT': '86400', 15:57:36 INFO - 'USER': 'cltbld', 15:57:36 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:36 INFO - '_': '/tools/buildbot/bin/python'} 15:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:57:37 INFO - Running setup.py (path:/tmp/pip-wHYD1e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:57:37 INFO - Running setup.py (path:/tmp/pip-ilw06Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:57:37 INFO - Running setup.py (path:/tmp/pip-M428Hv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:57:37 INFO - Running setup.py (path:/tmp/pip-ZnIf4V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:57:37 INFO - Running setup.py (path:/tmp/pip-2TI_MN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:57:37 INFO - Running setup.py (path:/tmp/pip-lBWZg2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:57:37 INFO - Running setup.py (path:/tmp/pip-yQF3it-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:57:37 INFO - Running setup.py (path:/tmp/pip-ndGkF5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:57:37 INFO - Running setup.py (path:/tmp/pip-o5d3hQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:57:37 INFO - Running setup.py (path:/tmp/pip-2tGvPC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:57:37 INFO - Running setup.py (path:/tmp/pip-P0v2rg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:57:37 INFO - Running setup.py (path:/tmp/pip-uABwef-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:57:37 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)) 15:57:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:57:37 INFO - Running setup.py (path:/tmp/pip-bYZUX2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:57:38 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)) 15:57:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:57:38 INFO - Running setup.py (path:/tmp/pip-y_WmIX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:57:38 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)) 15:57:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:57:38 INFO - Running setup.py (path:/tmp/pip-kWRUFd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:57:38 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)) 15:57:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:57:38 INFO - Running setup.py (path:/tmp/pip-3eycSh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:57:38 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)) 15:57:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:57:38 INFO - Running setup.py (path:/tmp/pip-TQfbP1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:57:38 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)) 15:57:38 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)) 15:57:38 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)) 15:57:38 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)) 15:57:38 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:57:38 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:57:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:57:38 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 15:57:38 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 15:57:38 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 15:57:38 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 15:57:38 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 15:57:38 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 15:57:40 INFO - Downloading blessings-1.5.1.tar.gz 15:57:40 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 15:57:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:57:40 INFO - Installing collected packages: blessings 15:57:40 INFO - Running setup.py install for blessings 15:57:40 INFO - Successfully installed blessings 15:57:40 INFO - Cleaning up... 15:57:40 INFO - Return code: 0 15:57:40 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:57:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:57:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:57:40 INFO - Reading from file tmpfile_stdout 15:57:40 INFO - Current package versions: 15:57:40 INFO - argparse == 1.2.1 15:57:40 INFO - blessings == 1.5.1 15:57:40 INFO - blobuploader == 1.2.4 15:57:40 INFO - docopt == 0.6.1 15:57:40 INFO - manifestparser == 1.1 15:57:40 INFO - mozInstall == 1.12 15:57:40 INFO - mozcrash == 0.16 15:57:40 INFO - mozdebug == 0.1 15:57:40 INFO - mozdevice == 0.46 15:57:40 INFO - mozfile == 1.2 15:57:40 INFO - mozhttpd == 0.7 15:57:40 INFO - mozinfo == 0.8 15:57:40 INFO - mozleak == 0.1 15:57:40 INFO - mozlog == 3.0 15:57:40 INFO - moznetwork == 0.27 15:57:40 INFO - mozprocess == 0.22 15:57:40 INFO - mozprofile == 0.27 15:57:40 INFO - mozrunner == 6.11 15:57:40 INFO - mozscreenshot == 0.1 15:57:40 INFO - mozsystemmonitor == 0.0 15:57:40 INFO - moztest == 0.7 15:57:40 INFO - mozversion == 1.4 15:57:40 INFO - psutil == 3.1.1 15:57:40 INFO - requests == 1.2.3 15:57:40 INFO - wsgiref == 0.1.2 15:57:40 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:57:40 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')]} 15:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:40 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')]} 15:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:40 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:40 INFO - Using env: {'DISPLAY': ':0', 15:57:40 INFO - 'HOME': '/home/cltbld', 15:57:40 INFO - 'LANG': 'en_US.UTF-8', 15:57:40 INFO - 'LANGUAGE': 'en_US:en', 15:57:40 INFO - 'LOGNAME': 'cltbld', 15:57:40 INFO - 'MAIL': '/var/mail/cltbld', 15:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:40 INFO - 'MOZ_NO_REMOTE': '1', 15:57:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:40 INFO - 'NO_EM_RESTART': '1', 15:57:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:40 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:40 INFO - 'SHELL': '/bin/bash', 15:57:40 INFO - 'SHLVL': '1', 15:57:40 INFO - 'TERM': 'linux', 15:57:40 INFO - 'TMOUT': '86400', 15:57:40 INFO - 'USER': 'cltbld', 15:57:40 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:40 INFO - '_': '/tools/buildbot/bin/python'} 15:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 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)) 15:57:40 INFO - Cleaning up... 15:57:40 INFO - Return code: 0 15:57:40 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:57:40 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')]} 15:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:40 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')]} 15:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:40 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:40 INFO - Using env: {'DISPLAY': ':0', 15:57:40 INFO - 'HOME': '/home/cltbld', 15:57:40 INFO - 'LANG': 'en_US.UTF-8', 15:57:40 INFO - 'LANGUAGE': 'en_US:en', 15:57:40 INFO - 'LOGNAME': 'cltbld', 15:57:40 INFO - 'MAIL': '/var/mail/cltbld', 15:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:40 INFO - 'MOZ_NO_REMOTE': '1', 15:57:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:40 INFO - 'NO_EM_RESTART': '1', 15:57:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:40 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:40 INFO - 'SHELL': '/bin/bash', 15:57:40 INFO - 'SHLVL': '1', 15:57:40 INFO - 'TERM': 'linux', 15:57:40 INFO - 'TMOUT': '86400', 15:57:40 INFO - 'USER': 'cltbld', 15:57:40 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:40 INFO - '_': '/tools/buildbot/bin/python'} 15:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:40 INFO - Downloading/unpacking jsonschema 15:57:40 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 15:57:40 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 15:57:40 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 15:57:40 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 15:57:40 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 15:57:40 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 15:57:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:57:42 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 15:57:42 INFO - Downloading/unpacking functools32 (from jsonschema) 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57:42 INFO - Downloading functools32-3.2.3-2.tar.gz 15:57:42 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 15:57:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:57:42 INFO - warning: no files found matching '*.txt' 15:57:42 INFO - no previously-included directories found matching 'build' 15:57:42 INFO - no previously-included directories found matching 'dist' 15:57:42 INFO - no previously-included directories found matching '.git*' 15:57:42 INFO - Installing collected packages: jsonschema, functools32 15:57:42 INFO - Running setup.py install for functools32 15:57:43 INFO - warning: no files found matching '*.txt' 15:57:43 INFO - no previously-included directories found matching 'build' 15:57:43 INFO - no previously-included directories found matching 'dist' 15:57:43 INFO - no previously-included directories found matching '.git*' 15:57:43 INFO - Successfully installed jsonschema functools32 15:57:43 INFO - Cleaning up... 15:57:43 INFO - Return code: 0 15:57:43 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:57:43 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')]} 15:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:43 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')]} 15:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:43 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f77e3491f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', '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 15:57:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 15:57:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 15:57:43 INFO - Using env: {'DISPLAY': ':0', 15:57:43 INFO - 'HOME': '/home/cltbld', 15:57:43 INFO - 'LANG': 'en_US.UTF-8', 15:57:43 INFO - 'LANGUAGE': 'en_US:en', 15:57:43 INFO - 'LOGNAME': 'cltbld', 15:57:43 INFO - 'MAIL': '/var/mail/cltbld', 15:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:43 INFO - 'MOZ_NO_REMOTE': '1', 15:57:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:43 INFO - 'NO_EM_RESTART': '1', 15:57:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:57:43 INFO - 'PWD': '/builds/slave/test-pgo', 15:57:43 INFO - 'SHELL': '/bin/bash', 15:57:43 INFO - 'SHLVL': '1', 15:57:43 INFO - 'TERM': 'linux', 15:57:43 INFO - 'TMOUT': '86400', 15:57:43 INFO - 'USER': 'cltbld', 15:57:43 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:43 INFO - '_': '/tools/buildbot/bin/python'} 15:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:43 INFO - Downloading/unpacking flake8 15:57:43 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 15:57:43 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 15:57:43 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 15:57:43 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 15:57:43 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 15:57:43 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 15:57:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:57:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 15:57:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:57:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 15:57:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 15:57:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57: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 15:57:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 15:57:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:57:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:57:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:57:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:57:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:57:45 INFO - no previously-included directories found matching 'docs/_build' 15:57:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:57:45 INFO - Running setup.py install for pep8 15:57:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:57:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:57:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:57:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:57:45 INFO - no previously-included directories found matching 'docs/_build' 15:57:45 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:57:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:57:45 INFO - Cleaning up... 15:57:45 INFO - Return code: 0 15:57:45 INFO - Running post-action listener: _resource_record_post_action 15:57:45 INFO - Running post-action listener: _start_resource_monitoring 15:57:45 INFO - Starting resource monitoring. 15:57:45 INFO - ##### 15:57:45 INFO - ##### Running install step. 15:57:45 INFO - ##### 15:57:45 INFO - Running pre-action listener: _resource_record_pre_action 15:57:45 INFO - Running main action method: install 15:57:45 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:57:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:57:46 INFO - Reading from file tmpfile_stdout 15:57:46 INFO - Detecting whether we're running mozinstall >=1.0... 15:57:46 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:57:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:57:46 INFO - Reading from file tmpfile_stdout 15:57:46 INFO - Output received: 15:57:46 INFO - Usage: mozinstall [options] installer 15:57:46 INFO - Options: 15:57:46 INFO - -h, --help show this help message and exit 15:57:46 INFO - -d DEST, --destination=DEST 15:57:46 INFO - Directory to install application into. [default: 15:57:46 INFO - "/builds/slave/test-pgo"] 15:57:46 INFO - --app=APP Application being installed. [default: firefox] 15:57:46 INFO - mkdir: /builds/slave/test-pgo/build/application 15:57:46 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 15:57:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 15:58:00 INFO - Reading from file tmpfile_stdout 15:58:00 INFO - Output received: 15:58:00 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:58:00 INFO - Running post-action listener: _resource_record_post_action 15:58:00 INFO - ##### 15:58:00 INFO - ##### Running run-tests step. 15:58:00 INFO - ##### 15:58:00 INFO - Running pre-action listener: _resource_record_pre_action 15:58:00 INFO - Running main action method: run_tests 15:58:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:58:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:58:00 INFO - Python 2.7.3 15:58:00 INFO - Return code: 0 15:58:00 INFO - grabbing minidump binary from tooltool 15:58:00 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')]} 15:58:00 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 0xed9350>, '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 0xeda250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeda6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:58:00 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 15:58:00 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 15:58:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:58:00 INFO - Return code: 0 15:58:00 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:58:00 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:58:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:58:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:58:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:58:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:58:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:58:01 INFO - Return code: 0 15:58:01 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/1452807597/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:58:01 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/1452807597/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 15:58:01 INFO - Using env: {'DISPLAY': ':0', 15:58:01 INFO - 'HOME': '/home/cltbld', 15:58:01 INFO - 'LANG': 'en_US.UTF-8', 15:58:01 INFO - 'LANGUAGE': 'en_US:en', 15:58:01 INFO - 'LOGNAME': 'cltbld', 15:58:01 INFO - 'MAIL': '/var/mail/cltbld', 15:58:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:58:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:01 INFO - 'MOZ_NO_REMOTE': '1', 15:58:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:01 INFO - 'NO_EM_RESTART': '1', 15:58:01 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:58:01 INFO - 'PWD': '/builds/slave/test-pgo', 15:58:01 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:58:01 INFO - 'SHELL': '/bin/bash', 15:58:01 INFO - 'SHLVL': '1', 15:58:01 INFO - 'TERM': 'linux', 15:58:01 INFO - 'TMOUT': '86400', 15:58:01 INFO - 'USER': 'cltbld', 15:58:01 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552', 15:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:01 INFO - '_': '/tools/buildbot/bin/python'} 15:58:01 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/1452807597/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 15:58:01 INFO - mozversion INFO | application_buildid: 20160114133957 15:58:01 INFO - mozversion INFO | application_changeset: db934cef3dffc02e56d8725dc6165bd8212dd4c6 15:58:01 INFO - mozversion INFO | application_display_name: Nightly 15:58:01 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:58:01 INFO - mozversion INFO | application_name: Firefox 15:58:01 INFO - mozversion INFO | application_remotingname: firefox 15:58:01 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:58:01 INFO - mozversion INFO | application_vendor: Mozilla 15:58:01 INFO - mozversion INFO | application_version: 44.0 15:58:01 INFO - mozversion INFO | platform_buildid: 20160114133957 15:58:01 INFO - mozversion INFO | platform_changeset: db934cef3dffc02e56d8725dc6165bd8212dd4c6 15:58:01 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:58:01 INFO - mozversion INFO | platform_version: 44.0 15:58:01 INFO - 2016-01-14 15:58:01,651 DEBUG : using testdate: 1452815881 15:58:01 INFO - 2016-01-14 15:58:01,651 DEBUG : actual date: 1452815881 15:58:01 INFO - 2016-01-14 15:58:01,659 INFO : starting webserver on 'localhost:53102' 15:58:01 INFO - 2016-01-14 15:58:01,660 INFO : Starting test suite talos-linux64-ix-046 15:58:01 INFO - 2016-01-14 15:58:01,660 INFO : Starting test dromaeo_css 15:58:01 INFO - 2016-01-14 15:58:01,660 DEBUG : operating with platform_type : linux_ 15:58:01 INFO - 2016-01-14 15:58:01,661 INFO : Initialising browser for dromaeo_css test... 15:58:01 INFO - 2016-01-14 15:58:01,676 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJJnBuD/profile http://localhost:53102/getInfo.html 15:58:05 INFO - 2016-01-14 15:58:05,373 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:58:05 INFO - 2016-01-14 15:58:05,373 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:58:05 INFO - 2016-01-14 15:58:05,373 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:58:05 INFO - 2016-01-14 15:58:05,373 DEBUG : BROWSER_OUTPUT: __metrics 15:58:05 INFO - 2016-01-14 15:58:05,434 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] 15:58:06 INFO - 2016-01-14 15:58:06,623 INFO : Browser initialized. 15:58:06 INFO - 2016-01-14 15:58:06,623 INFO : Running cycle 1/1 for dromaeo_css test... 15:58:06 INFO - 2016-01-14 15:58:06,624 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJJnBuD/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:58:18 INFO - 2016-01-14 15:58:18,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 129155072 15:58:18 INFO - 2016-01-14 15:58:18,118 DEBUG : BROWSER_OUTPUT: 15:59:09 INFO - 2016-01-14 15:59:09,013 DEBUG : BROWSER_OUTPUT: 1452815949012 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] 16:01:16 INFO - 2016-01-14 16:01:16,787 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53102/tests/dromaeo/cssquery-ext.html) 16:01:17 INFO - 2016-01-14 16:01:17,876 DEBUG : BROWSER_OUTPUT: RSS: Main: 238059520 16:01:17 INFO - 2016-01-14 16:01:17,876 DEBUG : BROWSER_OUTPUT: 16:04:19 INFO - 2016-01-14 16:04:19,888 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/dromaeo/cssquery-ext.html (next: http://localhost:53102/tests/dromaeo/cssquery-jquery.html) 16:04:20 INFO - 2016-01-14 16:04:20,448 DEBUG : BROWSER_OUTPUT: RSS: Main: 210821120 16:04:20 INFO - 2016-01-14 16:04:20,449 DEBUG : BROWSER_OUTPUT: 16:07:22 INFO - 2016-01-14 16:07:22,102 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53102/tests/dromaeo/cssquery-mootools.html) 16:07:22 INFO - 2016-01-14 16:07:22,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 206761984 16:07:22 INFO - 2016-01-14 16:07:22,664 DEBUG : BROWSER_OUTPUT: 16:10:24 INFO - 2016-01-14 16:10:24,325 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53102/tests/dromaeo/cssquery-prototype.html) 16:10:24 INFO - 2016-01-14 16:10:24,891 DEBUG : BROWSER_OUTPUT: RSS: Main: 191680512 16:10:24 INFO - 2016-01-14 16:10:24,891 DEBUG : BROWSER_OUTPUT: 16:13:27 INFO - 2016-01-14 16:13:27,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53102/tests/dromaeo/cssquery-yui.html) 16:13:27 INFO - 2016-01-14 16:13:27,781 DEBUG : BROWSER_OUTPUT: RSS: Main: 193798144 16:13:27 INFO - 2016-01-14 16:13:27,782 DEBUG : BROWSER_OUTPUT: 16:16:29 INFO - 2016-01-14 16:16:29,919 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/dromaeo/cssquery-yui.html (next: http://localhost:53102/tests/dromaeo/cssquery-dojo.html) 16:16:30 INFO - 2016-01-14 16:16:30,169 DEBUG : BROWSER_OUTPUT: RSS: Main: 285097984 16:16:30 INFO - 2016-01-14 16:16:30,169 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,169 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:16:30 INFO - 2016-01-14 16:16:30,170 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:16:30 INFO - 2016-01-14 16:16:30,170 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:16:30 INFO - 2016-01-14 16:16:30,170 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:16:30 INFO - 2016-01-14 16:16:30,170 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3289.5230191622213;3356.345512625039;3370.241695618486;3371.072954937392;3398.5072164536136;5494.917576236355;5527.336719593649;5540.445955404468;5577.414371490996;5591.832245032645;5564.666120032801;5570.053090974534;5582.106862901936;5597.580181486384;5613.298337707786;4949.331840201581;5081.034603425346;5101.948980510185;5153;5188.118019936601;4998.350214472113;5088.23676448532;5095.6178286628465;5112.488751124876;5194.662346947436;5126.128558145106;5197;5201.843944681666;5205.557527610139;5231.764570594332;4946.282788995575;5180.974095129519;5181.896362072737;5191.896162076738;5250.028744682227;4922.827701030446;5013;5061.721605311706;5066.695998240118;5078;5389.73051347435;5404.51359377654;5410.702411367373;5414.052540805383;5444.91832622511;5789.855253618651;5902.3507414184405;6031.849203769897;6037.4566289033755;6040.184575082351;5958.314793798682;5999.6700181490005;6024.668643224621;6026.939730602681;6060.3939606039485;5664.065429204176;5674.347450043216;5678.034734095214;5678.233438485793;5678.375378708339;6046.909296360558;6062.2406017290405;6132.325444201134;6140.5701600887505;6141.113676572004;5945.940540594625;5985.910211346833;5986.191864098378;5986.640801551921;5992.820215393545;6000.249968753906;6002.639841609517;6002.669853158118;6007.098935159761;6082.787102451393;5783.537317014629;5918.141869428909;5951.613145145551;6018.277806663141;6122.908156377658;6056.424639659225;6086.087086936906;6087.29996050451;6090.634561926298;6091.269047714302;4614.976925115353;4624.121416930772;4626.37543931565;4628.467726211461;4653.837115700934;4059.4925634295714;4071.0840060986634;4140.91718165635;4153;4153.7300075494995;5715.828525144252;5810.62230954995;5812.82561523155;5814.592978491465;5816.5928385013485;5874;6104;6123.693815309217;6125.9693701531205;6127.295361033449;5652.0391533439415;5683.431656834367;5685.516731077904;5696.715164241771;5733.397993210736;5243.947560524346;5250.632455728062;5253.028189784902;5258.947410525846;5282.207668849704;5983.431574000463;6083.087536869505;6143.293521245025;6143.477804386588;6149.077638354193;6026.126211699191;6113.836997521885;6115.756108518303;6116.749879287674;6125.479334256638;6201.131841542097;6203.193584833943;6203.31763506005;6204.7828326008375;6210.875782484235;5853.502452291603;5853.824385268449;5904.911426328523;5932.940670593239;5940.940590594038;6049.274087109575;6054.061620448838;6063.484603808725;6067.757289708362;6073.301570319293;5928.258967629046;5930.703464826655;5933.110033495011;5946.494547963472;5954.07711804671;5701.144828275792;5814.04068328721;5833.183354330482;5920.526357891272;5983.072623743326;5853.853653658693;5861.296644402699;5864.120381942743;5869.002269614261;5870.119482077723;5928.733207005781;5966.433188847052;5968.820935371946;5973.641581505123;5986.132010858314;5597.244372009726;5695.544356451391;5713.771449141987;5784.50831679312;5813.854653633693 16:16:30 INFO - 2016-01-14 16:16:30,170 DEBUG : BROWSER_OUTPUT: |1;ext.html;595.7229888102034;623.5759683415278;631.7473010795678;635.6694518850195;639.3957709964085;35335;43997;44283;44508.77745611267;44588.55411445884;47936.28095578569;48479.75760121203;48518.75740621301;48585.51414485854;48664.756676216566;7961.402894782915;8099.068607110169;9271.443713377184;9458.10148035939;10721.83917241242;10964.725881852977;11012.39431831249;11100.111991040738;11128.332300061982;11129.610463633775;10985.61550345734;11094.611688590901;11101.72245693856;11101.777964440706;11111.666650000512;10848.403337816417;10970.725731856728;11038.448077596127;11040.723981900437;11049.447527623586;10833.187510936712;10958.835617465744;10980.615678451215;10982.231243812936;10986.615468458565;45415;45584.3162352565;45760;45802.312965305544;45852;3524.1365865362895;3530.8234588270484;3532.258225772591;3558.733095017884;3658.615845336228;33257.66742332594;33680.49479257813;33709.662903370896;33846.32307353839;33896.661033389595;3708.61059588742;3714.461403096563;3727.627237276278;3728.7949162796035;3803.2013277211818;318966;320396;320738;321693;321851;62199.378006220264;63356.683216583624;68732.31267687259;69041;75931;71812.28187718056;71887.64056179686;72162;72221;72274;19189.520261993282;19340.323088691828;19382.8061719381;19393.4181974541;19401.708974365396;14783.260836958108;14867;14894.851051489562;14897.77653335201;14899.553013409615;22466.55066898653;22846.200382986517;22947.082116715144;22979.540409191723;23066.192683256006;8768.640860666606;8823.250023748053;8851.955740221258;8930.374873758774;9076.909230907739;911.0525053943824;968.5060619084318;975.4683697384942;1003.6587560229411;1020.7039958411978;30349.241268968453;30369.392612147632;30613.69386306153;30940;30970.845145774576;1059.9258051936445;1070.3738313086776;1209.57664817315;1277.993610031944;1295.514182181682;14471.710565788626;15925.681486370207;16026.772800271403;16117.355305787873;17455;454555;459005;459038;459966;461533;63131.684341577995;63799;63848.68075659592;63885;63932.680336598016;1359.4086572341064;1379.799929010288;1410.605030591436;1454.4946678504964;1479.7928290039404;31498.842505787325;31872.043838684876;32432;34356.82821585876;35847.64152358443;57962;58416.12375814451;58485.415145848;58512.707436462544;58536;348295;354910;355357;356047;357965;47398.526014739415;47808.760956194994;48197.75901120472;48780;49062.75468622634;51922.480775193275;52085.73957130342;52453.47546524486;52665.47334526606;53414.465855340946;42401.57598423976;43234.56765432305;43517;44218.3367249498;44943;43526.129477410905;43531.41967422493;44085;44115.35541614984;44230;40943;41633;42168.1566368677;42270;42345;36559.45160822537;37135;38415.6158438412;38457.23085538218;38537.421938671505;27396.17811465659;28139.437211255252;28140;28316;28479.28801779972 16:16:30 INFO - 2016-01-14 16:16:30,172 DEBUG : BROWSER_OUTPUT: |2;jquery.html;376.4353469795307;381.38025708224137;381.4507109761943;384.29098313611394;390.9413587961805;12998.93500532496;15686.332936034945;18162.455126346198;19744.308949186783;19745;15978.280977356018;19346.322878699248;19605.313814016514;19704.70442943357;19723.1124599393;5699.373068962411;5820.941790582082;5824.970875145618;5825.271841019872;5830.387809280028;5752.65484070955;5854.560907931901;5886.911696324559;5887.322957859837;5893.823185304448;5972.104184372335;5973.283206015262;5978.342382337939;5983.10253461982;5992.670403127826;5804.8839023219725;5909.586328956974;5923.200367950315;5940.6435613863305;5961.135635332874;5364.6512976656395;5365.302510673627;5372.516473517363;5372.811951581676;5377.650452720561;24005.15981940641;24101;24582.262532124067;24713.25860224196;24965.62551561728;2254.233560589391;2292.5529521743265;2456.5946618807875;2491.0907518755703;2496.3634273260386;14147.929260353736;14656.853431465655;14758.778618320734;14827.184504852228;15006.924965375103;1915.396650055228;1933.584279379926;1939.7284380186786;1948;1949.717290992798;69239.30760692379;70027.64986175037;70608.6469567654;71767.2823271771;73790.99999999946;7794.571298578632;7895.644695988694;7977.561234132062;8099.716509922125;8107.22981316774;9612;9612.66355677548;9624.807503850025;9625.663101791404;9633.181179599813;6391;6401.263854656681;6439.130717353191;6442.903356449657;6448.742050317935;6953.930460695429;7006.194287656985;7027.508074434741;7045.788626341218;7088.362047415757;3480.930381392358;3573.19603089308;3640.0899775056237;3711.2206436648607;3726.6646001859963;1279.0662816144268;1285.948562057526;1287.7746394380945;1301.7917133258634;1319.7426501832051;470.3509157362886;476.7115894883615;517.3429744224813;548.4323724944663;553.7950958145432;12673.366331683379;13015.869841301466;13226.272555009567;13346.666333341744;13351.265680387478;537.6962016460687;543.0415316965559;543.9773226334465;552.0532287127595;564.7430419159273;6202.782902598387;7023.824404389931;7111.217766045731;7206.351428371367;7244.239354867768;607450;625565;625803;630124;647078;81870.18129818745;82770.17229827745;84887.15112848792;85138.57430712807;85352.14647853567;580.9651420914759;595.6217801695955;595.919550860637;598.1685457214476;598.3627436779816;12345;12449;12509.812352814897;12564.937175314431;12591.622251332476;35580.64419355877;35926;36255;37122.44316335311;37189.25621487609;46000.30999535076;47243.763781180875;47507.28739068848;52285.215721764966;52359.214611780095;7087.078679771596;7492.962535187289;7659.463837531319;7884.329831964347;7892.723754668788;12008.459619317326;12851.485940562272;13483.460661573426;13579.185248885098;13580.660483487993;11849.763004740029;11972.64082077539;11977.820332694842;12097.153199275966;12189.390530473618;11497.310161390344;11742.354170520703;11788.882111178778;11820;11887.346195958959;11536.134789890624;11787.469563869476;11946.044316454836;12053.337066461429;12144.939275303566;7740.535567865946;7768.262015108556;7839.451238413257;7851;7859.253370929752;11850.940745296562;12200.328981906081;12326.383680816103;12338.75322493563;12362.505499780267 16:16:30 INFO - 2016-01-14 16:16:30,173 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1565.389498095742;1579.8736101111913;1638.4265507072523;1861.7858946221188;1946.7079938009297;7469.813254668622;7625.046869141357;8100.31147352475;8224.095349511548;8271.25558699717;8108.878366824488;8212.384071194654;8291.91708082919;8300.584970751468;8309.584520773953;2341.6604592334143;2378.583747844129;2382.916597919073;2384.070212617081;2385.2724918899817;2064.0814837397365;2101.7898210179014;2382.5115851250453;2386.534625747971;2429.9878500607474;2228.832837537183;2262.332611879491;2266.637338025916;2268.897899594522;2271.1823743452346;2367.041348253951;2381.714194296687;2382.7736365045293;2383.3445802404303;2384.5707772600927;2333.1367394064137;2402.987985060081;2404.831661783676;2408.397900524869;2410.554047501218;8255.050669173062;8274.255317021498;8276.875846862302;8318.12659670732;8325.66697332106;5007.4992500749995;5081.441041485434;5095.464976177485;5098.031374038921;5099.15863882459;3152.8581213845205;3211.6627754085716;3271.460209065501;3272.656371081026;3284.26104126572;1028.7736697926443;1030.237624158125;1032.633415137623;1062.3519653011656;1068.604616291977;11335.716607084807;11336.546538138464;11344.432778361132;11353.886461135447;11360.659180224606;4522.773861306987;4527.501974782771;4554.88612784683;4560.863174104782;4571.9314210286875;9690.70927872165;9771.51142442875;9775.120239178508;9776.755581110529;9783.315167938319;4949.430815456197;5011.699298042129;5012.624053195952;5041.319421878073;5063;4592.471865735416;4593.747343896118;4597.42532183477;4615.976920115378;4622.352870598119;3866.033491627093;3898.688104951597;3906.3554513505524;3928.155446578999;3949.980250098731;1149.0635132367095;1154.699778057711;1160.9361485118231;1163.1973937982764;1259.2633309514038;1150.9296354390497;1166.854143232079;1196.4017991004498;1362.8705272999248;1382.7994940733538;7939.563324017119;8016.398770092266;8017.959910200411;8278.875816862752;8284.8343033139;1131.2025022359217;1191.3090407563593;1226.4910062324036;1232.9876701233052;1257.4844313831468;6404.167458230493;6493.155889734304;6520.510961677893;6524.086627872101;6532.150820393318;9963.302568820258;10079;10120;10124.392536447836;10131.949340253399;9483.430994140374;9978.550965206583;10037.849432258521;10040.497975101362;10054.64808731691;7645.923540764632;7694.538327700356;7791.688332466638;7797.064352277763;7797.532148071021;7795.230942172582;7830.473057753683;7848.568328741974;7870;7939.563324017235;8729.65081396737;8952.910470895467;8992.55037248148;9001.279897608307;9003.684871029482;8996.86504702443;9130.49782261982;9157.679481218125;9261.53692315395;9283.953580232055;7921.089074756477;7968.721094761656;7998;8008.359331253602;8029.036515618163;7945.046594408708;8030.277275045274;8052.396070294869;8063.758087257392;8129.4715843469985;6431.871362572816;7492.962535187289;7498.62506874665;7594.468387212842;7732.497387669783;7410.258974102547;7498.775036748906;7518.924810751822;7598.962005189938;7624.58064806441;7696;7742.032245969254;7744;7745.883811742941;7759.3404560610725;6823.317668233137;6824.556403833735;6835.316468353125;6848.1097457330225;7014.368706816411;7202.639868006474;7218.494705370574;7250.274972502708;7254.45591580623;7269.091363579552 16:16:30 INFO - 2016-01-14 16:16:30,174 DEBUG : BROWSER_OUTPUT: |4;prototype.html;372.1700607644952;375.46683709133026;377.18904355635397;377.89796754876187;385.86109000759706;5867.413258674131;5943.167956486095;5949.107633854924;5950.79172228972;6007.969960150193;5716.971415142919;5803.390643982384;5807;5907.379725128864;5916.171735956958;4620;4636.744979026153;4656.534346565341;4659.347691323201;4680.344751734743;4627.3521706961055;4634.745089020103;4661.44062712475;4668.929966050512;4671.019085991946;4480.820767169309;4551.817927282905;4575.039241759235;4643.141018911512;4651.651126165534;4596.7931443085145;4620.052889157748;4629.074185162947;4638.884027899313;4644.303354496818;4142.896427589334;4145.79271036447;4151.647109995654;4152.294110001307;4152.792360381969;5601.019821531216;5625.043742563774;5625.971870140664;5628.943710562883;5637.915431268534;5271.841844744664;5351.545118664879;5354.384245811742;5358.356997160366;5363.222332761767;6260.906086408708;6265.404786545271;6265.8120256392385;6273.435390814803;6275.623462592259;4493.326001099861;4496.7077139985795;4500.504944456107;4500.774961251957;4503.842365517224;6355.3644635536075;6406.231252249713;6468.902966455507;6484.221893372778;6529.575577587441;4617.376654151713;4620.53794620542;4620.67655264135;4635.443746750411;4636.397268355092;4680.15757163707;4682.648801339845;4684.601808846286;4685.929711054337;4686.461056978423;5355.678659280377;5369.006733754268;5370.791571896292;5374.3013408256675;5377.946220537745;6255.74977000924;6382.393672601095;6385.425311721968;6386.808395748135;6395.712192951329;1688.1221764682296;1703.2080082761574;1705.360489816319;1706.4624643237341;1707.7865266841645;1132.1791701016862;1146.2091157101572;1148.1274231584057;1149.781541507111;1177.264209868832;6201.0388389799655;6204.348543402968;6206.255249370014;6207.81376558704;6212.534059945522;6554.3117972613145;6558.114654521674;6559.901601475982;6561.179852518435;6567.2447668518735;6023.789167379121;6098.420650038239;6104.38956104386;6194.101855231056;6199.163112979781;3588.551431071116;3616;3622.69207117398;3626.3472574936236;3626.8367923443507;6713.429358504484;6716.932830671729;6755.662216889136;6756.932430675729;6788.49086318528;6584.637844918576;6801.625910574867;6804.8298792529595;6810.62541560219;6810.863782724317;6794.93205067943;6799.32006799326;6802.04771332004;6802.387785099365;6805.489588280899;6560.901586476111;6617.503687223381;6618.338166183536;6623.437007854193;6629.569078009914;6371.745130194926;6469.45009674183;6495.285518593046;6496.187976502937;6496.610203387811;6742;6744.898826517704;6745.325467453215;6745.93254067453;6748.358905903931;4577.954220457753;4620.099080679235;4620.2607582786595;4622.630189584758;4627.028324051987;6420.26166990802;6533.509986750918;6533.705983230671;6545;6559.6064236145985;6362.04569314588;6376.075469056868;6392;6393.4885209184085;6399.296077431386;6368.172137622079;6376.713047912763;6377.521685873671;6381.234251889803;6389.584676995981;6141.723622436912;6266.843328916813;6271.811845644638;6272.2159730033745;6273.686315684289;6412.487001039999;6440.29156792743;6489.1888513935755;6491.188601424822;6504.414602685781;6296.968515157395;6330.968345158245;6339.5562310637815;6352.460040896575;6352.809415717536 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: |5;yui.html;830.6054624053576;903.3631289940593;917.5916717060902;940.3699521320715;950.5817440326256;14520.927395363022;14756.335964881579;14886.25568721565;14898.776518352233;14906.925465372671;12793.680157996032;15730.685386292267;15933.44232951847;15942.920285398586;15979.201039948015;776.5845272779064;777.0985656638301;892.9062448442934;907.8048219632777;912.2884150362728;918.5545010669853;927.1470247372433;930.0095398400715;931.1666058877335;933.1927882381759;970.3013830042358;973.956171972263;977.325645304741;981.6024510073431;982.9262805289596;957.6121670723368;967.2503809547586;968.7142293023575;968.7432830299941;970.2819913264169;705.3087973785667;705.4215543254534;706.1737766812842;706.4736771105507;707.0419581467089;14791.40834366624;14886.851131488655;14905.403783848742;14913.403463861432;14916.477923272634;11194.776104477865;11382.430878456127;11433.942830285878;11798.705032374173;12134.453949572291;10341.121004714609;10986.615468458645;11003.834942475869;11233.10135189183;11309;3476.8087755173456;3482.703970162539;3494.8776792812127;3525.4888041233876;3581.8209089545417;49973.50026499761;50108.24837627439;50732.49267507352;51119;51137.48862511364;8190.549519776469;8193.139720329398;8299.950174389636;8326.333893288644;8341.624626891684;13998.30008499571;13999.720005599831;13999.790003149961;14010.85989140116;14025.509107181404;7355.963220183865;7410.851782964311;7560.281773231534;7604.695812167562;7618.809529761689;4908.263760435963;4955.776990035457;5021.573166280834;5029.597632189416;5061.645684802029;2998.85005749716;3024.803387779787;3027.409655117275;3030.4545181867034;3032.1358412852674;475.5125995854277;485.046882875152;485.3375142929875;487.90729761345943;489.28075728678783;626.9498440124779;629.8588759593958;630.2657404124236;656.9737210511526;684.5926673629183;8440.155984401634;8677.435966662148;8764.605592748341;8851.46891186518;8886.86669699955;597.9372165922603;604.4620287943734;625.727808403346;675.560885424478;708.5996412027188;4324.286492728665;4446.73319600825;4500.954990450119;4584.679072464895;4682.929756053662;65358.67320663366;66167.33832661709;66651.33348666548;67177.3282267171;67266;57717;57905;58069.12896306559;58204;58408.70795645995;53774.731126344115;54014;54060.45939540633;54134.72932635312;54283.72858135684;9366;9393.859092113487;9505.382150160218;9515.143637072697;9584.808303834025;39741.40387894126;39742.80128599339;40155;40750;41162.588374115876;47820.521794782995;50929.490705092474;51888.48111518836;51948;52400.47599523956;8520.872186917077;8863.157999989991;8904.24313933323;8953.104689530996;8965.955170224108;13809.585712428643;14019.929900350433;14026.22855742903;14038.298085095908;14246;11871.169018168644;12165.69585760363;12260.26438413698;12344.13591048618;12429.937850310691;12294.323812190416;12462.127651064338;12630.43163057683;12760.42578084007;13694.794578081535;10709;11077.944610276896;11549.88450115488;12283.877161228273;12473.812892806796;10418.322809017389;10523.210759193244;10780.137588993019;10967.561297548009;11313.434328283718;9036.728898133066;10327.431991240554;10384.37693738378;10395.58417663285;10496.895031049897 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,827 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: __startTimestamp1452816990166__endTimestamp 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,175 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:16:30 INFO - 2016-01-14 16:16:30,176 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:16:30 INFO - 2016-01-14 16:16:30,176 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,176 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5591.49 Median:5814.59 stddev:622.14 (10.7%) stddev-sans-first:597.26 16:16:30 INFO - 2016-01-14 16:16:30,176 DEBUG : BROWSER_OUTPUT: Values: 3289.5 3356.3 3370.2 3371.1 3398.5 5494.9 5527.3 5540.4 5577.4 5591.8 5564.7 5570.1 5582.1 5597.6 5613.3 4949.3 5081.0 5101.9 5153.0 5188.1 4998.4 5088.2 5095.6 5112.5 5194.7 5126.1 5197.0 5201.8 5205.6 5231.8 4946.3 5181.0 5181.9 5191.9 5250.0 4922.8 5013.0 5061.7 5066.7 5078.0 5389.7 5404.5 5410.7 5414.1 5444.9 5789.9 5902.4 6031.8 6037.5 6040.2 5958.3 5999.7 6024.7 6026.9 6060.4 5664.1 5674.3 5678.0 5678.2 5678.4 6046.9 6062.2 6132.3 6140.6 6141.1 5945.9 5985.9 5986.2 5986.6 5992.8 6000.2 6002.6 6002.7 6007.1 6082.8 5783.5 5918.1 5951.6 6018.3 6122.9 6056.4 6086.1 6087.3 6090.6 6091.3 4615.0 4624.1 4626.4 4628.5 4653.8 4059.5 4071.1 4140.9 4153.0 4153.7 5715.8 5810.6 5812.8 5814.6 5816.6 5874.0 6104.0 6123.7 6126.0 6127.3 5652.0 5683.4 5685.5 5696.7 5733.4 5243.9 5250.6 5253.0 5258.9 5282.2 5983.4 6083.1 6143.3 6143.5 6149.1 6026.1 6113.8 6115.8 6116.7 6125.5 6201.1 6203.2 6203.3 6204.8 6210.9 5853.5 5853.8 5904.9 5932.9 5940.9 6049.3 6054.1 6063.5 6067.8 6073.3 5928.3 5930.7 5933.1 5946.5 5954.1 5701.1 5814.0 5833.2 5920.5 5983.1 5853.9 5861.3 5864.1 5869.0 5870.1 5928.7 5966.4 5968.8 5973.6 5986.1 5597.2 5695.5 5713.8 5784.5 5813.9 16:16:30 INFO - 2016-01-14 16:16:30,176 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,176 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:57584.14 Median:20421.81 stddev:100471.14 (492.0%) stddev-sans-first:100661.88 16:16:30 INFO - 2016-01-14 16:16:30,177 DEBUG : BROWSER_OUTPUT: Values: 595.7 623.6 631.7 635.7 639.4 35335.0 43997.0 44283.0 44508.8 44588.6 47936.3 48479.8 48518.8 48585.5 48664.8 7961.4 8099.1 9271.4 9458.1 10721.8 10964.7 11012.4 11100.1 11128.3 11129.6 10985.6 11094.6 11101.7 11101.8 11111.7 10848.4 10970.7 11038.4 11040.7 11049.4 10833.2 10958.8 10980.6 10982.2 10986.6 45415.0 45584.3 45760.0 45802.3 45852.0 3524.1 3530.8 3532.3 3558.7 3658.6 33257.7 33680.5 33709.7 33846.3 33896.7 3708.6 3714.5 3727.6 3728.8 3803.2 318966.0 320396.0 320738.0 321693.0 321851.0 62199.4 63356.7 68732.3 69041.0 75931.0 71812.3 71887.6 72162.0 72221.0 72274.0 19189.5 19340.3 19382.8 19393.4 19401.7 14783.3 14867.0 14894.9 14897.8 14899.6 22466.6 22846.2 22947.1 22979.5 23066.2 8768.6 8823.3 8852.0 8930.4 9076.9 911.1 968.5 975.5 1003.7 1020.7 30349.2 30369.4 30613.7 30940.0 30970.8 1059.9 1070.4 1209.6 1278.0 1295.5 14471.7 15925.7 16026.8 16117.4 17455.0 454555.0 459005.0 459038.0 459966.0 461533.0 63131.7 63799.0 63848.7 63885.0 63932.7 1359.4 1379.8 1410.6 1454.5 1479.8 31498.8 31872.0 32432.0 34356.8 35847.6 57962.0 58416.1 58485.4 58512.7 58536.0 348295.0 354910.0 355357.0 356047.0 357965.0 47398.5 47808.8 48197.8 48780.0 49062.8 51922.5 52085.7 52453.5 52665.5 53414.5 42401.6 43234.6 43517.0 44218.3 44943.0 43526.1 43531.4 44085.0 44115.4 44230.0 40943.0 41633.0 42168.2 42270.0 42345.0 36559.5 37135.0 38415.6 38457.2 38537.4 27396.2 28139.4 28140.0 28316.0 28479.3 16:16:30 INFO - 2016-01-14 16:16:30,178 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,178 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31202.68 Median:26401.28 stddev:102688.73 (389.0%) stddev-sans-first:102950.71 16:16:30 INFO - 2016-01-14 16:16:30,179 DEBUG : BROWSER_OUTPUT: Values: 376.4 381.4 381.5 384.3 390.9 12998.9 15686.3 18162.5 19744.3 19745.0 15978.3 19346.3 19605.3 19704.7 19723.1 5699.4 5820.9 5825.0 5825.3 5830.4 5752.7 5854.6 5886.9 5887.3 5893.8 5972.1 5973.3 5978.3 5983.1 5992.7 5804.9 5909.6 5923.2 5940.6 5961.1 5364.7 5365.3 5372.5 5372.8 5377.7 24005.2 24101.0 24582.3 24713.3 24965.6 2254.2 2292.6 2456.6 2491.1 2496.4 14147.9 14656.9 14758.8 14827.2 15006.9 1915.4 1933.6 1939.7 1948.0 1949.7 69239.3 70027.6 70608.6 71767.3 73791.0 7794.6 7895.6 7977.6 8099.7 8107.2 9612.0 9612.7 9624.8 9625.7 9633.2 6391.0 6401.3 6439.1 6442.9 6448.7 6953.9 7006.2 7027.5 7045.8 7088.4 3480.9 3573.2 3640.1 3711.2 3726.7 1279.1 1285.9 1287.8 1301.8 1319.7 470.4 476.7 517.3 548.4 553.8 12673.4 13015.9 13226.3 13346.7 13351.3 537.7 543.0 544.0 552.1 564.7 6202.8 7023.8 7111.2 7206.4 7244.2 607450.0 625565.0 625803.0 630124.0 647078.0 81870.2 82770.2 84887.2 85138.6 85352.1 581.0 595.6 595.9 598.2 598.4 12345.0 12449.0 12509.8 12564.9 12591.6 35580.6 35926.0 36255.0 37122.4 37189.3 46000.3 47243.8 47507.3 52285.2 52359.2 7087.1 7493.0 7659.5 7884.3 7892.7 12008.5 12851.5 13483.5 13579.2 13580.7 11849.8 11972.6 11977.8 12097.2 12189.4 11497.3 11742.4 11788.9 11820.0 11887.3 11536.1 11787.5 11946.0 12053.3 12144.9 7740.5 7768.3 7839.5 7851.0 7859.3 11850.9 12200.3 12326.4 12338.8 12362.5 16:16:30 INFO - 2016-01-14 16:16:30,179 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,179 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5864.29 Median:5098.60 stddev:3048.70 (59.8%) stddev-sans-first:3040.13 16:16:30 INFO - 2016-01-14 16:16:30,179 DEBUG : BROWSER_OUTPUT: Values: 1565.4 1579.9 1638.4 1861.8 1946.7 7469.8 7625.0 8100.3 8224.1 8271.3 8108.9 8212.4 8291.9 8300.6 8309.6 2341.7 2378.6 2382.9 2384.1 2385.3 2064.1 2101.8 2382.5 2386.5 2430.0 2228.8 2262.3 2266.6 2268.9 2271.2 2367.0 2381.7 2382.8 2383.3 2384.6 2333.1 2403.0 2404.8 2408.4 2410.6 8255.1 8274.3 8276.9 8318.1 8325.7 5007.5 5081.4 5095.5 5098.0 5099.2 3152.9 3211.7 3271.5 3272.7 3284.3 1028.8 1030.2 1032.6 1062.4 1068.6 11335.7 11336.5 11344.4 11353.9 11360.7 4522.8 4527.5 4554.9 4560.9 4571.9 9690.7 9771.5 9775.1 9776.8 9783.3 4949.4 5011.7 5012.6 5041.3 5063.0 4592.5 4593.7 4597.4 4616.0 4622.4 3866.0 3898.7 3906.4 3928.2 3950.0 1149.1 1154.7 1160.9 1163.2 1259.3 1150.9 1166.9 1196.4 1362.9 1382.8 7939.6 8016.4 8018.0 8278.9 8284.8 1131.2 1191.3 1226.5 1233.0 1257.5 6404.2 6493.2 6520.5 6524.1 6532.2 9963.3 10079.0 10120.0 10124.4 10131.9 9483.4 9978.6 10037.8 10040.5 10054.6 7645.9 7694.5 7791.7 7797.1 7797.5 7795.2 7830.5 7848.6 7870.0 7939.6 8729.7 8952.9 8992.6 9001.3 9003.7 8996.9 9130.5 9157.7 9261.5 9284.0 7921.1 7968.7 7998.0 8008.4 8029.0 7945.0 8030.3 8052.4 8063.8 8129.5 6431.9 7493.0 7498.6 7594.5 7732.5 7410.3 7498.8 7518.9 7599.0 7624.6 7696.0 7742.0 7744.0 7745.9 7759.3 6823.3 6824.6 6835.3 6848.1 7014.4 7202.6 7218.5 7250.3 7254.5 7269.1 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5346.21 Median:6061.10 stddev:1570.98 (25.9%) stddev-sans-first:1530.39 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: Values: 372.2 375.5 377.2 377.9 385.9 5867.4 5943.2 5949.1 5950.8 6008.0 5717.0 5803.4 5807.0 5907.4 5916.2 4620.0 4636.7 4656.5 4659.3 4680.3 4627.4 4634.7 4661.4 4668.9 4671.0 4480.8 4551.8 4575.0 4643.1 4651.7 4596.8 4620.1 4629.1 4638.9 4644.3 4142.9 4145.8 4151.6 4152.3 4152.8 5601.0 5625.0 5626.0 5628.9 5637.9 5271.8 5351.5 5354.4 5358.4 5363.2 6260.9 6265.4 6265.8 6273.4 6275.6 4493.3 4496.7 4500.5 4500.8 4503.8 6355.4 6406.2 6468.9 6484.2 6529.6 4617.4 4620.5 4620.7 4635.4 4636.4 4680.2 4682.6 4684.6 4685.9 4686.5 5355.7 5369.0 5370.8 5374.3 5377.9 6255.7 6382.4 6385.4 6386.8 6395.7 1688.1 1703.2 1705.4 1706.5 1707.8 1132.2 1146.2 1148.1 1149.8 1177.3 6201.0 6204.3 6206.3 6207.8 6212.5 6554.3 6558.1 6559.9 6561.2 6567.2 6023.8 6098.4 6104.4 6194.1 6199.2 3588.6 3616.0 3622.7 3626.3 3626.8 6713.4 6716.9 6755.7 6756.9 6788.5 6584.6 6801.6 6804.8 6810.6 6810.9 6794.9 6799.3 6802.0 6802.4 6805.5 6560.9 6617.5 6618.3 6623.4 6629.6 6371.7 6469.5 6495.3 6496.2 6496.6 6742.0 6744.9 6745.3 6745.9 6748.4 4578.0 4620.1 4620.3 4622.6 4627.0 6420.3 6533.5 6533.7 6545.0 6559.6 6362.0 6376.1 6392.0 6393.5 6399.3 6368.2 6376.7 6377.5 6381.2 6389.6 6141.7 6266.8 6271.8 6272.2 6273.7 6412.5 6440.3 6489.2 6491.2 6504.4 6297.0 6331.0 6339.6 6352.5 6352.8 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14989.21 Median:5045.62 stddev:18196.38 (360.6%) stddev-sans-first:18216.36 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: Values: 830.6 903.4 917.6 940.4 950.6 14520.9 14756.3 14886.3 14898.8 14906.9 12793.7 15730.7 15933.4 15942.9 15979.2 776.6 777.1 892.9 907.8 912.3 918.6 927.1 930.0 931.2 933.2 970.3 974.0 977.3 981.6 982.9 957.6 967.3 968.7 968.7 970.3 705.3 705.4 706.2 706.5 707.0 14791.4 14886.9 14905.4 14913.4 14916.5 11194.8 11382.4 11433.9 11798.7 12134.5 10341.1 10986.6 11003.8 11233.1 11309.0 3476.8 3482.7 3494.9 3525.5 3581.8 49973.5 50108.2 50732.5 51119.0 51137.5 8190.5 8193.1 8300.0 8326.3 8341.6 13998.3 13999.7 13999.8 14010.9 14025.5 7356.0 7410.9 7560.3 7604.7 7618.8 4908.3 4955.8 5021.6 5029.6 5061.6 2998.9 3024.8 3027.4 3030.5 3032.1 475.5 485.0 485.3 487.9 489.3 626.9 629.9 630.3 657.0 684.6 8440.2 8677.4 8764.6 8851.5 8886.9 597.9 604.5 625.7 675.6 708.6 4324.3 4446.7 4501.0 4584.7 4682.9 65358.7 66167.3 66651.3 67177.3 67266.0 57717.0 57905.0 58069.1 58204.0 58408.7 53774.7 54014.0 54060.5 54134.7 54283.7 9366.0 9393.9 9505.4 9515.1 9584.8 39741.4 39742.8 40155.0 40750.0 41162.6 47820.5 50929.5 51888.5 51948.0 52400.5 8520.9 8863.2 8904.2 8953.1 8966.0 13809.6 14019.9 14026.2 14038.3 14246.0 11871.2 12165.7 12260.3 12344.1 12429.9 12294.3 12462.1 12630.4 12760.4 13694.8 10709.0 11077.9 11549.9 12283.9 12473.8 10418.3 10523.2 10780.1 10967.6 11313.4 9036.7 10327.4 10384.4 10395.6 10496.9 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:16:30 INFO - 2016-01-14 16:16:30,180 DEBUG : BROWSER_OUTPUT: 16:16:30 INFO - 2016-01-14 16:16:30,747 INFO : Browser exited with error code: 0 16:16:30 INFO - 2016-01-14 16:16:30,768 INFO : Completed test dromaeo_css (00:18:29) 16:16:30 INFO - 2016-01-14 16:16:30,768 INFO : Starting test kraken 16:16:30 INFO - 2016-01-14 16:16:30,768 DEBUG : operating with platform_type : linux_ 16:16:30 INFO - 2016-01-14 16:16:30,768 INFO : Initialising browser for kraken test... 16:16:30 INFO - 2016-01-14 16:16:30,774 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYkJmTD/profile http://localhost:53102/getInfo.html 16:16:34 INFO - 2016-01-14 16:16:34,206 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:16:34 INFO - 2016-01-14 16:16:34,207 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:16:34 INFO - 2016-01-14 16:16:34,207 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:16:34 INFO - 2016-01-14 16:16:34,207 DEBUG : BROWSER_OUTPUT: __metrics 16:16:34 INFO - 2016-01-14 16:16:34,372 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] 16:16:35 INFO - 2016-01-14 16:16:35,459 INFO : Browser initialized. 16:16:35 INFO - 2016-01-14 16:16:35,459 INFO : Running cycle 1/1 for kraken test... 16:16:35 INFO - 2016-01-14 16:16:35,459 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYkJmTD/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:16:46 INFO - 2016-01-14 16:16:46,981 DEBUG : BROWSER_OUTPUT: RSS: Main: 130576384 16:16:46 INFO - 2016-01-14 16:16:46,982 DEBUG : BROWSER_OUTPUT: 16:16:49 INFO - 2016-01-14 16:16:49,419 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53102/tests/kraken/driver.html?testName=audio-beat-detection) 16:16:49 INFO - 2016-01-14 16:16:49,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 163942400 16:16:49 INFO - 2016-01-14 16:16:49,987 DEBUG : BROWSER_OUTPUT: 16:16:52 INFO - 2016-01-14 16:16:52,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53102/tests/kraken/driver.html?testName=audio-dft) 16:16:53 INFO - 2016-01-14 16:16:53,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 161316864 16:16:53 INFO - 2016-01-14 16:16:53,097 DEBUG : BROWSER_OUTPUT: 16:16:55 INFO - 2016-01-14 16:16:55,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53102/tests/kraken/driver.html?testName=audio-fft) 16:16:56 INFO - 2016-01-14 16:16:56,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 157388800 16:16:56 INFO - 2016-01-14 16:16:56,425 DEBUG : BROWSER_OUTPUT: 16:16:58 INFO - 2016-01-14 16:16:58,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53102/tests/kraken/driver.html?testName=audio-oscillator) 16:16:58 INFO - 2016-01-14 16:16:58,919 DEBUG : BROWSER_OUTPUT: RSS: Main: 157937664 16:16:58 INFO - 2016-01-14 16:16:58,919 DEBUG : BROWSER_OUTPUT: 16:17:01 INFO - 2016-01-14 16:17:01,053 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53102/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:17:01 INFO - 2016-01-14 16:17:01,614 DEBUG : BROWSER_OUTPUT: RSS: Main: 154726400 16:17:01 INFO - 2016-01-14 16:17:01,614 DEBUG : BROWSER_OUTPUT: 16:17:04 INFO - 2016-01-14 16:17:04,514 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53102/tests/kraken/driver.html?testName=imaging-darkroom) 16:17:05 INFO - 2016-01-14 16:17:05,081 DEBUG : BROWSER_OUTPUT: RSS: Main: 154701824 16:17:05 INFO - 2016-01-14 16:17:05,082 DEBUG : BROWSER_OUTPUT: 16:17:08 INFO - 2016-01-14 16:17:08,432 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53102/tests/kraken/driver.html?testName=imaging-desaturate) 16:17:08 INFO - 2016-01-14 16:17:08,999 DEBUG : BROWSER_OUTPUT: RSS: Main: 156999680 16:17:08 INFO - 2016-01-14 16:17:08,999 DEBUG : BROWSER_OUTPUT: 16:17:11 INFO - 2016-01-14 16:17:11,725 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53102/tests/kraken/driver.html?testName=json-parse-financial) 16:17:12 INFO - 2016-01-14 16:17:12,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 155459584 16:17:12 INFO - 2016-01-14 16:17:12,292 DEBUG : BROWSER_OUTPUT: 16:17:13 INFO - 2016-01-14 16:17:13,799 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53102/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:17:14 INFO - 2016-01-14 16:17:14,353 DEBUG : BROWSER_OUTPUT: RSS: Main: 160305152 16:17:14 INFO - 2016-01-14 16:17:14,353 DEBUG : BROWSER_OUTPUT: 16:17:16 INFO - 2016-01-14 16:17:16,091 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:17:16 INFO - 2016-01-14 16:17:16,693 DEBUG : BROWSER_OUTPUT: RSS: Main: 158478336 16:17:16 INFO - 2016-01-14 16:17:16,693 DEBUG : BROWSER_OUTPUT: 16:17:18 INFO - 2016-01-14 16:17:18,624 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:17:19 INFO - 2016-01-14 16:17:19,192 DEBUG : BROWSER_OUTPUT: RSS: Main: 163864576 16:17:19 INFO - 2016-01-14 16:17:19,192 DEBUG : BROWSER_OUTPUT: 16:17:21 INFO - 2016-01-14 16:17:21,487 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:17:22 INFO - 2016-01-14 16:17:22,053 DEBUG : BROWSER_OUTPUT: RSS: Main: 167665664 16:17:22 INFO - 2016-01-14 16:17:22,054 DEBUG : BROWSER_OUTPUT: 16:17:24 INFO - 2016-01-14 16:17:24,476 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:17:25 INFO - 2016-01-14 16:17:25,042 DEBUG : BROWSER_OUTPUT: RSS: Main: 166907904 16:17:25 INFO - 2016-01-14 16:17:25,042 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,652 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53102/tests/kraken/driver.html?testName=ai-astar) 16:17:26 INFO - 2016-01-14 16:17:26,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 224907264 16:17:26 INFO - 2016-01-14 16:17:26,898 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,899 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:17:26 INFO - 2016-01-14 16:17:26,900 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:17:26 INFO - 2016-01-14 16:17:26,900 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:17:26 INFO - 2016-01-14 16:17:26,900 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:17:26 INFO - 2016-01-14 16:17:26,900 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;104;111;110;109;99;108;105;108 16:17:26 INFO - 2016-01-14 16:17:26,901 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;121;151;159;123;152;146;128;168;126 16:17:26 INFO - 2016-01-14 16:17:26,901 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;161;161;160;159;159;162;161;161 16:17:26 INFO - 2016-01-14 16:17:26,901 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;70;71;147;71;71;72;72;145;70 16:17:26 INFO - 2016-01-14 16:17:26,901 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;89;131;88;89;89;140;89;90;136 16:17:26 INFO - 2016-01-14 16:17:26,901 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;111;109;109;109;108;109;110;110;109 16:17:26 INFO - 2016-01-14 16:17:26,902 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;145;145;145;145;144;144;145 16:17:26 INFO - 2016-01-14 16:17:26,902 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;98;97;97;97;97;97;97;97;97;97 16:17:26 INFO - 2016-01-14 16:17:26,902 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;56;56;56;56;54;57;56;53;53 16:17:26 INFO - 2016-01-14 16:17:26,902 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;63;64;63;64;63;63;63;63 16:17:26 INFO - 2016-01-14 16:17:26,902 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;76;76;77;78;78;77;79;78;77 16:17:26 INFO - 2016-01-14 16:17:26,903 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;110;107;113;114;115;116;115;114;115;115 16:17:26 INFO - 2016-01-14 16:17:26,903 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;131;146;135;133;134;134;133;135;137;124 16:17:26 INFO - 2016-01-14 16:17:26,903 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;60;60;62;63;60;61;61;60;61 16:17:26 INFO - 2016-01-14 16:17:26,903 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:17:26 INFO - 2016-01-14 16:17:26,903 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:17:26 INFO - 2016-01-14 16:17:26,904 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,874 16:17:26 INFO - 2016-01-14 16:17:26,904 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:17:26 INFO - 2016-01-14 16:17:26,904 DEBUG : BROWSER_OUTPUT: __startTimestamp1452817046898__endTimestamp 16:17:26 INFO - 2016-01-14 16:17:26,904 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,906 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:17:26 INFO - 2016-01-14 16:17:26,906 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:17:26 INFO - 2016-01-14 16:17:26,906 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,906 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.50 Median:110.00 stddev:3.81 (3.5%) stddev-sans-first:3.93 16:17:26 INFO - 2016-01-14 16:17:26,907 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 104.0 111.0 110.0 109.0 99.0 108.0 105.0 108.0 16:17:26 INFO - 2016-01-14 16:17:26,907 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,907 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.30 Median:150.00 stddev:16.55 (11.0%) stddev-sans-first:17.37 16:17:26 INFO - 2016-01-14 16:17:26,907 DEBUG : BROWSER_OUTPUT: Values: 149.0 121.0 151.0 159.0 123.0 152.0 146.0 128.0 168.0 126.0 16:17:26 INFO - 2016-01-14 16:17:26,908 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,908 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 16:17:26 INFO - 2016-01-14 16:17:26,908 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 161.0 161.0 160.0 159.0 159.0 162.0 161.0 161.0 16:17:26 INFO - 2016-01-14 16:17:26,908 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,908 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.10 Median:71.00 stddev:31.58 (44.5%) stddev-sans-first:33.08 16:17:26 INFO - 2016-01-14 16:17:26,909 DEBUG : BROWSER_OUTPUT: Values: 72.0 70.0 71.0 147.0 71.0 71.0 72.0 72.0 145.0 70.0 16:17:26 INFO - 2016-01-14 16:17:26,909 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,910 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.10 Median:89.00 stddev:22.58 (25.4%) stddev-sans-first:23.45 16:17:26 INFO - 2016-01-14 16:17:26,910 DEBUG : BROWSER_OUTPUT: Values: 90.0 89.0 131.0 88.0 89.0 89.0 140.0 89.0 90.0 136.0 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 109.0 109.0 109.0 108.0 109.0 110.0 110.0 109.0 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:144.90 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 145.0 145.0 145.0 145.0 144.0 144.0 145.0 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,911 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:97.10 Median:97.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: Values: 98.0 97.0 97.0 97.0 97.0 97.0 97.0 97.0 97.0 97.0 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.40 (2.5%) stddev-sans-first:1.48 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: Values: 55.0 56.0 56.0 56.0 56.0 54.0 57.0 56.0 53.0 53.0 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 63.0 64.0 63.0 64.0 63.0 63.0 63.0 63.0 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:77.50 stddev:0.95 (1.2%) stddev-sans-first:1.00 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: Values: 77.0 76.0 76.0 77.0 78.0 78.0 77.0 79.0 78.0 77.0 16:17:26 INFO - 2016-01-14 16:17:26,912 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.40 Median:115.00 stddev:2.80 (2.4%) stddev-sans-first:2.68 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: Values: 110.0 107.0 113.0 114.0 115.0 116.0 115.0 114.0 115.0 115.0 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.20 Median:134.50 stddev:5.43 (4.0%) stddev-sans-first:5.64 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: Values: 131.0 146.0 135.0 133.0 134.0 134.0 133.0 135.0 137.0 124.0 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.40 (2.3%) stddev-sans-first:1.05 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: Values: 64.0 60.0 60.0 62.0 63.0 60.0 61.0 61.0 60.0 61.0 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:17:26 INFO - 2016-01-14 16:17:26,913 DEBUG : BROWSER_OUTPUT: 16:17:27 INFO - 2016-01-14 16:17:27,559 INFO : Browser exited with error code: 0 16:17:27 INFO - 2016-01-14 16:17:27,610 INFO : Completed test kraken (00:00:56) 16:17:27 INFO - 2016-01-14 16:17:27,610 INFO : Starting test v8_7 16:17:27 INFO - 2016-01-14 16:17:27,610 DEBUG : operating with platform_type : linux_ 16:17:27 INFO - 2016-01-14 16:17:27,610 INFO : Initialising browser for v8_7 test... 16:17:27 INFO - 2016-01-14 16:17:27,616 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1WnraQ/profile http://localhost:53102/getInfo.html 16:17:31 INFO - 2016-01-14 16:17:31,138 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:17:31 INFO - 2016-01-14 16:17:31,138 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:17:31 INFO - 2016-01-14 16:17:31,138 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:17:31 INFO - 2016-01-14 16:17:31,138 DEBUG : BROWSER_OUTPUT: __metrics 16:17:31 INFO - 2016-01-14 16:17:31,300 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] 16:17:32 INFO - 2016-01-14 16:17:32,447 INFO : Browser initialized. 16:17:32 INFO - 2016-01-14 16:17:32,447 INFO : Running cycle 1/1 for v8_7 test... 16:17:32 INFO - 2016-01-14 16:17:32,448 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1WnraQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:17:43 INFO - 2016-01-14 16:17:43,992 DEBUG : BROWSER_OUTPUT: RSS: Main: 127651840 16:17:43 INFO - 2016-01-14 16:17:43,992 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,320 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53102/tests/v8_7/run.html (next: http://localhost:53102/tests/v8_7/run.html) 16:18:05 INFO - 2016-01-14 16:18:05,570 DEBUG : BROWSER_OUTPUT: RSS: Main: 415682560 16:18:05 INFO - 2016-01-14 16:18:05,570 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,572 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:18:05 INFO - 2016-01-14 16:18:05,572 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:18:05 INFO - 2016-01-14 16:18:05,572 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:18:05 INFO - 2016-01-14 16:18:05,572 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:18:05 INFO - 2016-01-14 16:18:05,572 DEBUG : BROWSER_OUTPUT: |0;Richards;163.5322976287817 16:18:05 INFO - 2016-01-14 16:18:05,572 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.35775127768312 16:18:05 INFO - 2016-01-14 16:18:05,572 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.70680044593087 16:18:05 INFO - 2016-01-14 16:18:05,573 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5010 16:18:05 INFO - 2016-01-14 16:18:05,573 DEBUG : BROWSER_OUTPUT: |4;RayTrace;942.5070688030161 16:18:05 INFO - 2016-01-14 16:18:05,573 DEBUG : BROWSER_OUTPUT: |5;Earley;562.7462014631401 16:18:05 INFO - 2016-01-14 16:18:05,573 DEBUG : BROWSER_OUTPUT: |6;Boyer;10468.75 16:18:05 INFO - 2016-01-14 16:18:05,573 DEBUG : BROWSER_OUTPUT: |7;RegExp;23880.95238095238 16:18:05 INFO - 2016-01-14 16:18:05,573 DEBUG : BROWSER_OUTPUT: |8;Splay;534.3633457645178 16:18:05 INFO - 2016-01-14 16:18:05,573 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 16:18:05 INFO - 2016-01-14 16:18:05,574 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:18:05 INFO - 2016-01-14 16:18:05,574 DEBUG : BROWSER_OUTPUT: __startTimestamp1452817085571__endTimestamp 16:18:05 INFO - 2016-01-14 16:18:05,574 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,574 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:18:05 INFO - 2016-01-14 16:18:05,574 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:18:05 INFO - 2016-01-14 16:18:05,574 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.53 Median:163.53 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: Values: 163.5 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.36 Median:170.36 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: Values: 170.4 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.71 Median:278.71 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: Values: 278.7 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5010.00 Median:5010.00 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: Values: 5010.0 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:942.51 Median:942.51 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,576 DEBUG : BROWSER_OUTPUT: Values: 942.5 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:562.75 Median:562.75 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: Values: 562.7 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10468.75 Median:10468.75 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: Values: 10468.8 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23880.95 Median:23880.95 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,577 DEBUG : BROWSER_OUTPUT: Values: 23881.0 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:534.36 Median:534.36 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: Values: 534.4 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: Values: 4822.1 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:18:05 INFO - 2016-01-14 16:18:05,578 DEBUG : BROWSER_OUTPUT: 16:18:06 INFO - 2016-01-14 16:18:06,159 INFO : Browser exited with error code: 0 16:18:06 INFO - 2016-01-14 16:18:06,175 INFO : Completed test v8_7 (00:00:38) 16:18:06 INFO - 2016-01-14 16:18:06,642 INFO : Completed test suite (00:20:04) 16:18:06 INFO - 2016-01-14 16:18:06,643 DEBUG : Working with test: dromaeo_css 16:18:06 INFO - 2016-01-14 16:18:06,643 DEBUG : Generating results file: dromaeo_css 16:18:06 INFO - 2016-01-14 16:18:06,644 DEBUG : Working with test: kraken 16:18:06 INFO - 2016-01-14 16:18:06,644 DEBUG : Generating results file: kraken 16:18:06 INFO - 2016-01-14 16:18:06,644 INFO : javascript benchmark 16:18:06 INFO - 2016-01-14 16:18:06,644 DEBUG : Working with test: v8_7 16:18:06 INFO - 2016-01-14 16:18:06,644 DEBUG : Generating results file: v8_7 16:18:06 INFO - 2016-01-14 16:18:06,645 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:18:06 INFO - 2016-01-14 16:18:06,692 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:18:06 INFO - 2016-01-14 16:18:06,692 DEBUG : process_Request line: dromaeo_css 7285.88 graph.html#tests=[[72,53,35]] 16:18:06 INFO - 2016-01-14 16:18:06,692 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:18:06 INFO - 2016-01-14 16:18:06,719 DEBUG : process_Request line: kraken 1455.30 graph.html#tests=[[232,53,35]] 16:18:06 INFO - 2016-01-14 16:18:06,719 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:18:06 INFO - 2016-01-14 16:18:06,747 DEBUG : process_Request line: v8_7 23280.77 graph.html#tests=[[230,53,35]] 16:18:06 INFO - 2016-01-14 16:18:06,749 INFO : javascript benchmark 16:18:06 INFO - 2016-01-14 16:18:06,752 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3289.5230191622213, 3356.345512625039, 3370.241695618486, 3371.072954937392, 3398.5072164536136, 5494.917576236355, 5527.336719593649, 5540.445955404468, 5577.414371490996, 5591.832245032645, 5564.666120032801, 5570.053090974534, 5582.106862901936, 5597.580181486384, 5613.298337707786, 4949.331840201581, 5081.034603425346, 5101.948980510185, 5153.0, 5188.118019936601, 4998.350214472113, 5088.23676448532, 5095.6178286628465, 5112.488751124876, 5194.662346947436, 5126.128558145106, 5197.0, 5201.843944681666, 5205.557527610139, 5231.764570594332, 4946.282788995575, 5180.974095129519, 5181.896362072737, 5191.896162076738, 5250.028744682227, 4922.827701030446, 5013.0, 5061.721605311706, 5066.695998240118, 5078.0, 5389.73051347435, 5404.51359377654, 5410.702411367373, 5414.052540805383, 5444.91832622511, 5789.855253618651, 5902.3507414184405, 6031.849203769897, 6037.4566289033755, 6040.184575082351, 5958.314793798682, 5999.6700181490005, 6024.668643224621, 6026.939730602681, 6060.3939606039485, 5664.065429204176, 5674.347450043216, 5678.034734095214, 5678.233438485793, 5678.375378708339, 6046.909296360558, 6062.2406017290405, 6132.325444201134, 6140.5701600887505, 6141.113676572004, 5945.940540594625, 5985.910211346833, 5986.191864098378, 5986.640801551921, 5992.820215393545, 6000.249968753906, 6002.639841609517, 6002.669853158118, 6007.098935159761, 6082.787102451393, 5783.537317014629, 5918.141869428909, 5951.613145145551, 6018.277806663141, 6122.908156377658, 6056.424639659225, 6086.087086936906, 6087.29996050451, 6090.634561926298, 6091.269047714302, 4614.976925115353, 4624.121416930772, 4626.37543931565, 4628.467726211461, 4653.837115700934, 4059.4925634295714, 4071.0840060986634, 4140.91718165635, 4153.0, 4153.7300075494995, 5715.828525144252, 5810.62230954995, 5812.82561523155, 5814.592978491465, 5816.5928385013485, 5874.0, 6104.0, 6123.693815309217, 6125.9693701531205, 6127.295361033449, 5652.0391533439415, 5683.431656834367, 5685.516731077904, 5696.715164241771, 5733.397993210736, 5243.947560524346, 5250.632455728062, 5253.028189784902, 5258.947410525846, 5282.207668849704, 5983.431574000463, 6083.087536869505, 6143.293521245025, 6143.477804386588, 6149.077638354193, 6026.126211699191, 6113.836997521885, 6115.756108518303, 6116.749879287674, 6125.479334256638, 6201.131841542097, 6203.193584833943, 6203.31763506005, 6204.7828326008375, 6210.875782484235, 5853.502452291603, 5853.824385268449, 5904.911426328523, 5932.940670593239, 5940.940590594038, 6049.274087109575, 6054.061620448838, 6063.484603808725, 6067.757289708362, 6073.301570319293, 5928.258967629046, 5930.703464826655, 5933.110033495011, 5946.494547963472, 5954.07711804671, 5701.144828275792, 5814.04068328721, 5833.183354330482, 5920.526357891272, 5983.072623743326, 5853.853653658693, 5861.296644402699, 5864.120381942743, 5869.002269614261, 5870.119482077723, 5928.733207005781, 5966.433188847052, 5968.820935371946, 5973.641581505123, 5986.132010858314, 5597.244372009726, 5695.544356451391, 5713.771449141987, 5784.50831679312, 5813.854653633693], "unit": "ms", "name": "dojo.html", "value": 5550.884994735824}, {"lowerIsBetter": false, "replicates": [595.7229888102034, 623.5759683415278, 631.7473010795678, 635.6694518850195, 639.3957709964085, 35335.0, 43997.0, 44283.0, 44508.77745611267, 44588.55411445884, 47936.28095578569, 48479.75760121203, 48518.75740621301, 48585.51414485854, 48664.756676216566, 7961.402894782915, 8099.068607110169, 9271.443713377184, 9458.10148035939, 10721.83917241242, 10964.725881852977, 11012.39431831249, 11100.111991040738, 11128.332300061982, 11129.610463633775, 10985.61550345734, 11094.611688590901, 11101.72245693856, 11101.777964440706, 11111.666650000512, 10848.403337816417, 10970.725731856728, 11038.448077596127, 11040.723981900437, 11049.447527623586, 10833.187510936712, 10958.835617465744, 10980.615678451215, 10982.231243812936, 10986.615468458565, 45415.0, 45584.3162352565, 45760.0, 45802.312965305544, 45852.0, 3524.1365865362895, 3530.8234588270484, 3532.258225772591, 3558.733095017884, 3658.615845336228, 33257.66742332594, 33680.49479257813, 33709.662903370896, 33846.32307353839, 33896.661033389595, 3708.61059588742, 3714.461403096563, 3727.627237276278, 3728.7949162796035, 3803.2013277211818, 318966.0, 320396.0, 320738.0, 321693.0, 321851.0, 62199.378006220264, 63356.683216583624, 68732.31267687259, 69041.0, 75931.0, 71812.28187718056, 71887.64056179686, 72162.0, 72221.0, 72274.0, 19189.520261993282, 19340.323088691828, 19382.8061719381, 19393.4181974541, 19401.708974365396, 14783.260836958108, 14867.0, 14894.851051489562, 14897.77653335201, 14899.553013409615, 22466.55066898653, 22846.200382986517, 22947.082116715144, 22979.540409191723, 23066.192683256006, 8768.640860666606, 8823.250023748053, 8851.955740221258, 8930.374873758774, 9076.909230907739, 911.0525053943824, 968.5060619084318, 975.4683697384942, 1003.6587560229411, 1020.7039958411978, 30349.241268968453, 30369.392612147632, 30613.69386306153, 30940.0, 30970.845145774576, 1059.9258051936445, 1070.3738313086776, 1209.57664817315, 1277.993610031944, 1295.514182181682, 14471.710565788626, 15925.681486370207, 16026.772800271403, 16117.355305787873, 17455.0, 454555.0, 459005.0, 459038.0, 459966.0, 461533.0, 63131.684341577995, 63799.0, 63848.68075659592, 63885.0, 63932.680336598016, 1359.4086572341064, 1379.799929010288, 1410.605030591436, 1454.4946678504964, 1479.7928290039404, 31498.842505787325, 31872.043838684876, 32432.0, 34356.82821585876, 35847.64152358443, 57962.0, 58416.12375814451, 58485.415145848, 58512.707436462544, 58536.0, 348295.0, 354910.0, 355357.0, 356047.0, 357965.0, 47398.526014739415, 47808.760956194994, 48197.75901120472, 48780.0, 49062.75468622634, 51922.480775193275, 52085.73957130342, 52453.47546524486, 52665.47334526606, 53414.465855340946, 42401.57598423976, 43234.56765432305, 43517.0, 44218.3367249498, 44943.0, 43526.129477410905, 43531.41967422493, 44085.0, 44115.35541614984, 44230.0, 40943.0, 41633.0, 42168.1566368677, 42270.0, 42345.0, 36559.45160822537, 37135.0, 38415.6158438412, 38457.23085538218, 38537.421938671505, 27396.17811465659, 28139.437211255252, 28140.0, 28316.0, 28479.28801779972], "unit": "ms", "name": "ext.html", "value": 21620.115509663843}, {"lowerIsBetter": false, "replicates": [376.4353469795307, 381.38025708224137, 381.4507109761943, 384.29098313611394, 390.9413587961805, 12998.93500532496, 15686.332936034945, 18162.455126346198, 19744.308949186783, 19745.0, 15978.280977356018, 19346.322878699248, 19605.313814016514, 19704.70442943357, 19723.1124599393, 5699.373068962411, 5820.941790582082, 5824.970875145618, 5825.271841019872, 5830.387809280028, 5752.65484070955, 5854.560907931901, 5886.911696324559, 5887.322957859837, 5893.823185304448, 5972.104184372335, 5973.283206015262, 5978.342382337939, 5983.10253461982, 5992.670403127826, 5804.8839023219725, 5909.586328956974, 5923.200367950315, 5940.6435613863305, 5961.135635332874, 5364.6512976656395, 5365.302510673627, 5372.516473517363, 5372.811951581676, 5377.650452720561, 24005.15981940641, 24101.0, 24582.262532124067, 24713.25860224196, 24965.62551561728, 2254.233560589391, 2292.5529521743265, 2456.5946618807875, 2491.0907518755703, 2496.3634273260386, 14147.929260353736, 14656.853431465655, 14758.778618320734, 14827.184504852228, 15006.924965375103, 1915.396650055228, 1933.584279379926, 1939.7284380186786, 1948.0, 1949.717290992798, 69239.30760692379, 70027.64986175037, 70608.6469567654, 71767.2823271771, 73790.99999999946, 7794.571298578632, 7895.644695988694, 7977.561234132062, 8099.716509922125, 8107.22981316774, 9612.0, 9612.66355677548, 9624.807503850025, 9625.663101791404, 9633.181179599813, 6391.0, 6401.263854656681, 6439.130717353191, 6442.903356449657, 6448.742050317935, 6953.930460695429, 7006.194287656985, 7027.508074434741, 7045.788626341218, 7088.362047415757, 3480.930381392358, 3573.19603089308, 3640.0899775056237, 3711.2206436648607, 3726.6646001859963, 1279.0662816144268, 1285.948562057526, 1287.7746394380945, 1301.7917133258634, 1319.7426501832051, 470.3509157362886, 476.7115894883615, 517.3429744224813, 548.4323724944663, 553.7950958145432, 12673.366331683379, 13015.869841301466, 13226.272555009567, 13346.666333341744, 13351.265680387478, 537.6962016460687, 543.0415316965559, 543.9773226334465, 552.0532287127595, 564.7430419159273, 6202.782902598387, 7023.824404389931, 7111.217766045731, 7206.351428371367, 7244.239354867768, 607450.0, 625565.0, 625803.0, 630124.0, 647078.0, 81870.18129818745, 82770.17229827745, 84887.15112848792, 85138.57430712807, 85352.14647853567, 580.9651420914759, 595.6217801695955, 595.919550860637, 598.1685457214476, 598.3627436779816, 12345.0, 12449.0, 12509.812352814897, 12564.937175314431, 12591.622251332476, 35580.64419355877, 35926.0, 36255.0, 37122.44316335311, 37189.25621487609, 46000.30999535076, 47243.763781180875, 47507.28739068848, 52285.215721764966, 52359.214611780095, 7087.078679771596, 7492.962535187289, 7659.463837531319, 7884.329831964347, 7892.723754668788, 12008.459619317326, 12851.485940562272, 13483.460661573426, 13579.185248885098, 13580.660483487993, 11849.763004740029, 11972.64082077539, 11977.820332694842, 12097.153199275966, 12189.390530473618, 11497.310161390344, 11742.354170520703, 11788.882111178778, 11820.0, 11887.346195958959, 11536.134789890624, 11787.469563869476, 11946.044316454836, 12053.337066461429, 12144.939275303566, 7740.535567865946, 7768.262015108556, 7839.451238413257, 7851.0, 7859.253370929752, 11850.940745296562, 12200.328981906081, 12326.383680816103, 12338.75322493563, 12362.505499780267], "unit": "ms", "name": "jquery.html", "value": 8071.989750069472}, {"lowerIsBetter": false, "replicates": [1565.389498095742, 1579.8736101111913, 1638.4265507072523, 1861.7858946221188, 1946.7079938009297, 7469.813254668622, 7625.046869141357, 8100.31147352475, 8224.095349511548, 8271.25558699717, 8108.878366824488, 8212.384071194654, 8291.91708082919, 8300.584970751468, 8309.584520773953, 2341.6604592334143, 2378.583747844129, 2382.916597919073, 2384.070212617081, 2385.2724918899817, 2064.0814837397365, 2101.7898210179014, 2382.5115851250453, 2386.534625747971, 2429.9878500607474, 2228.832837537183, 2262.332611879491, 2266.637338025916, 2268.897899594522, 2271.1823743452346, 2367.041348253951, 2381.714194296687, 2382.7736365045293, 2383.3445802404303, 2384.5707772600927, 2333.1367394064137, 2402.987985060081, 2404.831661783676, 2408.397900524869, 2410.554047501218, 8255.050669173062, 8274.255317021498, 8276.875846862302, 8318.12659670732, 8325.66697332106, 5007.4992500749995, 5081.441041485434, 5095.464976177485, 5098.031374038921, 5099.15863882459, 3152.8581213845205, 3211.6627754085716, 3271.460209065501, 3272.656371081026, 3284.26104126572, 1028.7736697926443, 1030.237624158125, 1032.633415137623, 1062.3519653011656, 1068.604616291977, 11335.716607084807, 11336.546538138464, 11344.432778361132, 11353.886461135447, 11360.659180224606, 4522.773861306987, 4527.501974782771, 4554.88612784683, 4560.863174104782, 4571.9314210286875, 9690.70927872165, 9771.51142442875, 9775.120239178508, 9776.755581110529, 9783.315167938319, 4949.430815456197, 5011.699298042129, 5012.624053195952, 5041.319421878073, 5063.0, 4592.471865735416, 4593.747343896118, 4597.42532183477, 4615.976920115378, 4622.352870598119, 3866.033491627093, 3898.688104951597, 3906.3554513505524, 3928.155446578999, 3949.980250098731, 1149.0635132367095, 1154.699778057711, 1160.9361485118231, 1163.1973937982764, 1259.2633309514038, 1150.9296354390497, 1166.854143232079, 1196.4017991004498, 1362.8705272999248, 1382.7994940733538, 7939.563324017119, 8016.398770092266, 8017.959910200411, 8278.875816862752, 8284.8343033139, 1131.2025022359217, 1191.3090407563593, 1226.4910062324036, 1232.9876701233052, 1257.4844313831468, 6404.167458230493, 6493.155889734304, 6520.510961677893, 6524.086627872101, 6532.150820393318, 9963.302568820258, 10079.0, 10120.0, 10124.392536447836, 10131.949340253399, 9483.430994140374, 9978.550965206583, 10037.849432258521, 10040.497975101362, 10054.64808731691, 7645.923540764632, 7694.538327700356, 7791.688332466638, 7797.064352277763, 7797.532148071021, 7795.230942172582, 7830.473057753683, 7848.568328741974, 7870.0, 7939.563324017235, 8729.65081396737, 8952.910470895467, 8992.55037248148, 9001.279897608307, 9003.684871029482, 8996.86504702443, 9130.49782261982, 9157.679481218125, 9261.53692315395, 9283.953580232055, 7921.089074756477, 7968.721094761656, 7998.0, 8008.359331253602, 8029.036515618163, 7945.046594408708, 8030.277275045274, 8052.396070294869, 8063.758087257392, 8129.4715843469985, 6431.871362572816, 7492.962535187289, 7498.62506874665, 7594.468387212842, 7732.497387669783, 7410.258974102547, 7498.775036748906, 7518.924810751822, 7598.962005189938, 7624.58064806441, 7696.0, 7742.032245969254, 7744.0, 7745.883811742941, 7759.3404560610725, 6823.317668233137, 6824.556403833735, 6835.316468353125, 6848.1097457330225, 7014.368706816411, 7202.639868006474, 7218.494705370574, 7250.274972502708, 7254.45591580623, 7269.091363579552], "unit": "ms", "name": "mootools.html", "value": 4795.6365664552095}, {"lowerIsBetter": false, "replicates": [372.1700607644952, 375.46683709133026, 377.18904355635397, 377.89796754876187, 385.86109000759706, 5867.413258674131, 5943.167956486095, 5949.107633854924, 5950.79172228972, 6007.969960150193, 5716.971415142919, 5803.390643982384, 5807.0, 5907.379725128864, 5916.171735956958, 4620.0, 4636.744979026153, 4656.534346565341, 4659.347691323201, 4680.344751734743, 4627.3521706961055, 4634.745089020103, 4661.44062712475, 4668.929966050512, 4671.019085991946, 4480.820767169309, 4551.817927282905, 4575.039241759235, 4643.141018911512, 4651.651126165534, 4596.7931443085145, 4620.052889157748, 4629.074185162947, 4638.884027899313, 4644.303354496818, 4142.896427589334, 4145.79271036447, 4151.647109995654, 4152.294110001307, 4152.792360381969, 5601.019821531216, 5625.043742563774, 5625.971870140664, 5628.943710562883, 5637.915431268534, 5271.841844744664, 5351.545118664879, 5354.384245811742, 5358.356997160366, 5363.222332761767, 6260.906086408708, 6265.404786545271, 6265.8120256392385, 6273.435390814803, 6275.623462592259, 4493.326001099861, 4496.7077139985795, 4500.504944456107, 4500.774961251957, 4503.842365517224, 6355.3644635536075, 6406.231252249713, 6468.902966455507, 6484.221893372778, 6529.575577587441, 4617.376654151713, 4620.53794620542, 4620.67655264135, 4635.443746750411, 4636.397268355092, 4680.15757163707, 4682.648801339845, 4684.601808846286, 4685.929711054337, 4686.461056978423, 5355.678659280377, 5369.006733754268, 5370.791571896292, 5374.3013408256675, 5377.946220537745, 6255.74977000924, 6382.393672601095, 6385.425311721968, 6386.808395748135, 6395.712192951329, 1688.1221764682296, 1703.2080082761574, 1705.360489816319, 1706.4624643237341, 1707.7865266841645, 1132.1791701016862, 1146.2091157101572, 1148.1274231584057, 1149.781541507111, 1177.264209868832, 6201.0388389799655, 6204.348543402968, 6206.255249370014, 6207.81376558704, 6212.534059945522, 6554.3117972613145, 6558.114654521674, 6559.901601475982, 6561.179852518435, 6567.2447668518735, 6023.789167379121, 6098.420650038239, 6104.38956104386, 6194.101855231056, 6199.163112979781, 3588.551431071116, 3616.0, 3622.69207117398, 3626.3472574936236, 3626.8367923443507, 6713.429358504484, 6716.932830671729, 6755.662216889136, 6756.932430675729, 6788.49086318528, 6584.637844918576, 6801.625910574867, 6804.8298792529595, 6810.62541560219, 6810.863782724317, 6794.93205067943, 6799.32006799326, 6802.04771332004, 6802.387785099365, 6805.489588280899, 6560.901586476111, 6617.503687223381, 6618.338166183536, 6623.437007854193, 6629.569078009914, 6371.745130194926, 6469.45009674183, 6495.285518593046, 6496.187976502937, 6496.610203387811, 6742.0, 6744.898826517704, 6745.325467453215, 6745.93254067453, 6748.358905903931, 4577.954220457753, 4620.099080679235, 4620.2607582786595, 4622.630189584758, 4627.028324051987, 6420.26166990802, 6533.509986750918, 6533.705983230671, 6545.0, 6559.6064236145985, 6362.04569314588, 6376.075469056868, 6392.0, 6393.4885209184085, 6399.296077431386, 6368.172137622079, 6376.713047912763, 6377.521685873671, 6381.234251889803, 6389.584676995981, 6141.723622436912, 6266.843328916813, 6271.811845644638, 6272.2159730033745, 6273.686315684289, 6412.487001039999, 6440.29156792743, 6489.1888513935755, 6491.188601424822, 6504.414602685781, 6296.968515157395, 6330.968345158245, 6339.5562310637815, 6352.460040896575, 6352.809415717536], "unit": "ms", "name": "prototype.html", "value": 4853.668172686621}, {"lowerIsBetter": false, "replicates": [830.6054624053576, 903.3631289940593, 917.5916717060902, 940.3699521320715, 950.5817440326256, 14520.927395363022, 14756.335964881579, 14886.25568721565, 14898.776518352233, 14906.925465372671, 12793.680157996032, 15730.685386292267, 15933.44232951847, 15942.920285398586, 15979.201039948015, 776.5845272779064, 777.0985656638301, 892.9062448442934, 907.8048219632777, 912.2884150362728, 918.5545010669853, 927.1470247372433, 930.0095398400715, 931.1666058877335, 933.1927882381759, 970.3013830042358, 973.956171972263, 977.325645304741, 981.6024510073431, 982.9262805289596, 957.6121670723368, 967.2503809547586, 968.7142293023575, 968.7432830299941, 970.2819913264169, 705.3087973785667, 705.4215543254534, 706.1737766812842, 706.4736771105507, 707.0419581467089, 14791.40834366624, 14886.851131488655, 14905.403783848742, 14913.403463861432, 14916.477923272634, 11194.776104477865, 11382.430878456127, 11433.942830285878, 11798.705032374173, 12134.453949572291, 10341.121004714609, 10986.615468458645, 11003.834942475869, 11233.10135189183, 11309.0, 3476.8087755173456, 3482.703970162539, 3494.8776792812127, 3525.4888041233876, 3581.8209089545417, 49973.50026499761, 50108.24837627439, 50732.49267507352, 51119.0, 51137.48862511364, 8190.549519776469, 8193.139720329398, 8299.950174389636, 8326.333893288644, 8341.624626891684, 13998.30008499571, 13999.720005599831, 13999.790003149961, 14010.85989140116, 14025.509107181404, 7355.963220183865, 7410.851782964311, 7560.281773231534, 7604.695812167562, 7618.809529761689, 4908.263760435963, 4955.776990035457, 5021.573166280834, 5029.597632189416, 5061.645684802029, 2998.85005749716, 3024.803387779787, 3027.409655117275, 3030.4545181867034, 3032.1358412852674, 475.5125995854277, 485.046882875152, 485.3375142929875, 487.90729761345943, 489.28075728678783, 626.9498440124779, 629.8588759593958, 630.2657404124236, 656.9737210511526, 684.5926673629183, 8440.155984401634, 8677.435966662148, 8764.605592748341, 8851.46891186518, 8886.86669699955, 597.9372165922603, 604.4620287943734, 625.727808403346, 675.560885424478, 708.5996412027188, 4324.286492728665, 4446.73319600825, 4500.954990450119, 4584.679072464895, 4682.929756053662, 65358.67320663366, 66167.33832661709, 66651.33348666548, 67177.3282267171, 67266.0, 57717.0, 57905.0, 58069.12896306559, 58204.0, 58408.70795645995, 53774.731126344115, 54014.0, 54060.45939540633, 54134.72932635312, 54283.72858135684, 9366.0, 9393.859092113487, 9505.382150160218, 9515.143637072697, 9584.808303834025, 39741.40387894126, 39742.80128599339, 40155.0, 40750.0, 41162.588374115876, 47820.521794782995, 50929.490705092474, 51888.48111518836, 51948.0, 52400.47599523956, 8520.872186917077, 8863.157999989991, 8904.24313933323, 8953.104689530996, 8965.955170224108, 13809.585712428643, 14019.929900350433, 14026.22855742903, 14038.298085095908, 14246.0, 11871.169018168644, 12165.69585760363, 12260.26438413698, 12344.13591048618, 12429.937850310691, 12294.323812190416, 12462.127651064338, 12630.43163057683, 12760.42578084007, 13694.794578081535, 10709.0, 11077.944610276896, 11549.88450115488, 12283.877161228273, 12473.812892806796, 10418.322809017389, 10523.210759193244, 10780.137588993019, 10967.561297548009, 11313.434328283718, 9036.728898133066, 10327.431991240554, 10384.37693738378, 10395.58417663285, 10496.895031049897], "unit": "ms", "name": "yui.html", "value": 6633.369052899918}], "name": "dromaeo_css", "value": 7285.8778864840015}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 104.0, 111.0, 110.0, 109.0, 99.0, 108.0, 105.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.5}, {"lowerIsBetter": true, "replicates": [149.0, 121.0, 151.0, 159.0, 123.0, 152.0, 146.0, 128.0, 168.0, 126.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.3}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 161.0, 161.0, 160.0, 159.0, 159.0, 162.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [72.0, 70.0, 71.0, 147.0, 71.0, 71.0, 72.0, 72.0, 145.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 86.1}, {"lowerIsBetter": true, "replicates": [90.0, 89.0, 131.0, 88.0, 89.0, 89.0, 140.0, 89.0, 90.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 103.1}, {"lowerIsBetter": true, "replicates": [109.0, 111.0, 109.0, 109.0, 109.0, 108.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 144.0, 144.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 144.9}, {"lowerIsBetter": true, "replicates": [98.0, 97.0, 97.0, 97.0, 97.0, 97.0, 97.0, 97.0, 97.0, 97.0], "unit": "ms", "name": "imaging-desaturate", "value": 97.1}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 56.0, 56.0, 56.0, 54.0, 57.0, 56.0, 53.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.2}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 63.0, 64.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.3}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 76.0, 77.0, 78.0, 78.0, 77.0, 79.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.3}, {"lowerIsBetter": true, "replicates": [110.0, 107.0, 113.0, 114.0, 115.0, 116.0, 115.0, 114.0, 115.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.4}, {"lowerIsBetter": true, "replicates": [131.0, 146.0, 135.0, 133.0, 134.0, 134.0, 133.0, 135.0, 137.0, 124.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 134.2}, {"lowerIsBetter": true, "replicates": [64.0, 60.0, 60.0, 62.0, 63.0, 60.0, 61.0, 61.0, 60.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.2}], "name": "kraken", "value": 1455.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.5322976287817], "unit": "score", "name": "Richards", "value": 215.87172999999993}, {"lowerIsBetter": false, "replicates": [170.35775127768312], "unit": "score", "name": "DeltaBlue", "value": 388.1126600000002}, {"lowerIsBetter": false, "replicates": [278.70680044593087], "unit": "score", "name": "Encrypt", "value": 955.0574279999998}, {"lowerIsBetter": false, "replicates": [5010.0], "unit": "score", "name": "Decrypt", "value": 53.12994011976049}, {"lowerIsBetter": false, "replicates": [942.5070688030161], "unit": "score", "name": "RayTrace", "value": 785.1283289999999}, {"lowerIsBetter": false, "replicates": [562.7462014631401], "unit": "score", "name": "Earley", "value": 1184.3047510000004}, {"lowerIsBetter": false, "replicates": [10468.75], "unit": "score", "name": "Boyer", "value": 63.66213731343284}, {"lowerIsBetter": false, "replicates": [23880.95238095238], "unit": "score", "name": "RegExp", "value": 38.146929212362885}, {"lowerIsBetter": false, "replicates": [534.3633457645178], "unit": "score", "name": "Splay", "value": 152.50110368893328}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23280.768268299475}]} 16:18:06 INFO - RETURN: dromaeo_css: 7285.88 16:18:06 INFO - RETURN: kraken: 1455.30 16:18:06 INFO - RETURN: v8_7: 23280.77 16:18:06 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23280.77"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1455.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7285.88"}}} 16:18:06 INFO - Return code: 0 16:18:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:18:06 INFO - # TBPL SUCCESS # 16:18:06 INFO - Running post-action listener: _resource_record_post_action 16:18:06 INFO - Running post-run listener: _resource_record_post_run 16:18:07 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 319488; Write bytes: 503762944; Read time: 240; Write time: 467484 16:18:07 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 240816128; Read time: 0; Write time: 323420 16:18:08 INFO - run-tests - Wall time: 1206s; CPU: 13.0%; Read bytes: 319488; Write bytes: 262127616; Read time: 240; Write time: 144032 16:18:08 INFO - Running post-run listener: _upload_blobber_files 16:18:08 INFO - Blob upload gear active. 16:18:08 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:18:08 INFO - Copying logs to upload dir... 16:18:08 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.555664 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-01-14 16:18:07.794198) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 16:18:07.798012) ========= 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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009983 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452807597/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 16:18:07.850810) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:18:07.851183) ========= 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=652eb03db935cfc1c15ebda0000001ad-1452815827.705994-1055926552 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004545 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:18:07.903353) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 16:18:07.904099) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 16:18:07.904641) ========= ========= Total master_lag: 0.23 =========