builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1451937279.83 results: success (0) buildid: 20160104092938 builduid: 33996ca4e75d4e9bba0cf8b7c76cce33 revision: 0b7ba8736906 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.831302) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.831888) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.832289) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004230 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.850247) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.850635) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.863670) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.864087) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004564 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.914584) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.914952) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.915413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:39.915785) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-04 11:54:40-- 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% 223M=0s 2016-01-04 11:54:40 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102414 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:40.033156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:40.033512) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019600 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:40.083851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-04 11:54:40.084311) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0b7ba8736906 --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 0b7ba8736906 --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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-04 11:54:40,375 Setting DEBUG logging. 2016-01-04 11:54:40,375 attempt 1/10 2016-01-04 11:54:40,375 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0b7ba8736906?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-04 11:54:41,284 unpacking tar archive at: mozilla-beta-0b7ba8736906/testing/mozharness/ program finished with exit code 0 elapsedTime=1.151584 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-04 11:54:41.254488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:41.255034) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:41.266193) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:41.266505) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-04 11:54:41.266962) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-01-04 11:54:41.267299) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:54:41 INFO - MultiFileLogger online at 20160104 11:54:41 in /builds/slave/test-pgo 11:54:41 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 11:54:41 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 11:54:41 INFO - {'append_to_log': False, 11:54:41 INFO - 'base_work_dir': '/builds/slave/test-pgo', 11:54:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:54:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 11:54:41 INFO - 'branch': 'Mozilla-Beta', 11:54:41 INFO - 'buildbot_json_path': 'buildprops.json', 11:54:41 INFO - 'config_files': ('talos/linux_config.py',), 11:54:41 INFO - 'default_actions': ('clobber', 11:54:41 INFO - 'read-buildbot-config', 11:54:41 INFO - 'download-and-extract', 11:54:41 INFO - 'populate-webroot', 11:54:41 INFO - 'create-virtualenv', 11:54:41 INFO - 'install', 11:54:41 INFO - 'run-tests'), 11:54:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:54:41 INFO - 'download_minidump_stackwalk': True, 11:54:41 INFO - 'download_symbols': 'ondemand', 11:54:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:54:41 INFO - 'tooltool.py': '/tools/tooltool.py', 11:54:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:54:41 INFO - '/tools/misc-python/virtualenv.py')}, 11:54:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:54:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:54:41 INFO - 'installer_path': 'installer.exe', 11:54:41 INFO - 'log_level': 'info', 11:54:41 INFO - 'log_name': 'talos', 11:54:41 INFO - 'log_to_console': True, 11:54:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:54:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:54:41 INFO - 'opt_config_files': (), 11:54:41 INFO - 'pip_index': False, 11:54:41 INFO - 'sps_profile': False, 11:54:41 INFO - 'sps_profile_interval': 0, 11:54:41 INFO - 'suite': 'dromaeojs', 11:54:41 INFO - 'system_bits': '32', 11:54:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:54:41 INFO - 'title': 'talos-linux64-ix-006', 11:54:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:54:41 INFO - 'use_talos_json': True, 11:54:41 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 11:54:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:54:41 INFO - 'work_dir': 'build'} 11:54:41 INFO - ##### 11:54:41 INFO - ##### Running clobber step. 11:54:41 INFO - ##### 11:54:41 INFO - Running pre-action listener: _resource_record_pre_action 11:54:41 INFO - Running main action method: clobber 11:54:41 INFO - rmtree: /builds/slave/test-pgo/build 11:54:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 11:54:42 INFO - Running post-action listener: _resource_record_post_action 11:54:42 INFO - ##### 11:54:42 INFO - ##### Running read-buildbot-config step. 11:54:42 INFO - ##### 11:54:42 INFO - Running pre-action listener: _resource_record_pre_action 11:54:42 INFO - Running main action method: read_buildbot_config 11:54:42 INFO - Using buildbot properties: 11:54:42 INFO - { 11:54:42 INFO - "properties": { 11:54:42 INFO - "buildnumber": 81, 11:54:42 INFO - "product": "firefox", 11:54:42 INFO - "script_repo_revision": "production", 11:54:42 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 11:54:42 INFO - "repository": "", 11:54:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 11:54:42 INFO - "buildid": "20160104092938", 11:54:42 INFO - "slavename": "talos-linux64-ix-006", 11:54:42 INFO - "pgo_build": "True", 11:54:42 INFO - "basedir": "/builds/slave/test-pgo", 11:54:42 INFO - "project": "", 11:54:42 INFO - "platform": "ubuntu64_hw", 11:54:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:54:42 INFO - "slavebuilddir": "test", 11:54:42 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 11:54:42 INFO - "branch": "mozilla-beta", 11:54:42 INFO - "repo_path": "releases/mozilla-beta", 11:54:42 INFO - "stage_platform": "linux64-pgo", 11:54:42 INFO - "builduid": "33996ca4e75d4e9bba0cf8b7c76cce33", 11:54:42 INFO - "revision": "0b7ba8736906" 11:54:42 INFO - }, 11:54:42 INFO - "sourcestamp": { 11:54:42 INFO - "repository": "", 11:54:42 INFO - "hasPatch": false, 11:54:42 INFO - "project": "", 11:54:42 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 11:54:42 INFO - "changes": [ 11:54:42 INFO - { 11:54:42 INFO - "category": null, 11:54:42 INFO - "files": [ 11:54:42 INFO - { 11:54:42 INFO - "url": null, 11:54:42 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.tar.bz2" 11:54:42 INFO - } 11:54:42 INFO - ], 11:54:42 INFO - "repository": "", 11:54:42 INFO - "rev": "0b7ba8736906", 11:54:42 INFO - "who": "sendchange", 11:54:42 INFO - "when": 1451937107, 11:54:42 INFO - "number": 7413616, 11:54:42 INFO - "comments": "Bug 1234571 - unregister encoded-frame callback when releasing codec databases. r=pkerr, a=al", 11:54:42 INFO - "project": "", 11:54:42 INFO - "at": "Mon 04 Jan 2016 11:51:47", 11:54:42 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 11:54:42 INFO - "revlink": "", 11:54:42 INFO - "properties": [ 11:54:42 INFO - [ 11:54:42 INFO - "buildid", 11:54:42 INFO - "20160104092938", 11:54:42 INFO - "Change" 11:54:42 INFO - ], 11:54:42 INFO - [ 11:54:42 INFO - "builduid", 11:54:42 INFO - "33996ca4e75d4e9bba0cf8b7c76cce33", 11:54:42 INFO - "Change" 11:54:42 INFO - ], 11:54:42 INFO - [ 11:54:42 INFO - "pgo_build", 11:54:42 INFO - "True", 11:54:42 INFO - "Change" 11:54:42 INFO - ] 11:54:42 INFO - ], 11:54:42 INFO - "revision": "0b7ba8736906" 11:54:42 INFO - } 11:54:42 INFO - ], 11:54:42 INFO - "revision": "0b7ba8736906" 11:54:42 INFO - } 11:54:42 INFO - } 11:54:42 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.tar.bz2. 11:54:42 INFO - Running post-action listener: _resource_record_post_action 11:54:42 INFO - ##### 11:54:42 INFO - ##### Running download-and-extract step. 11:54:42 INFO - ##### 11:54:42 INFO - Running pre-action listener: _resource_record_pre_action 11:54:42 INFO - Running main action method: download_and_extract 11:54:42 INFO - mkdir: /builds/slave/test-pgo/build/tests 11:54:42 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')]} 11:54:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/test_packages.json 11:54:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 11:54:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 11:54:42 INFO - Downloaded 1270 bytes. 11:54:42 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 11:54:42 INFO - Using the following test package requirements: 11:54:42 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 11:54:42 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 11:54:42 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 11:54:42 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 11:54:42 INFO - u'jsshell-linux-x86_64.zip'], 11:54:42 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 11:54:42 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 11:54:42 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 11:54:42 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 11:54:42 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 11:54:42 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 11:54:42 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 11:54:42 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 11:54:42 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 11:54:42 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 11:54:42 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 11:54:42 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 11:54:42 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:54:42 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')]} 11:54:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.common.tests.zip 11:54:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:42 INFO - Downloaded 22573038 bytes. 11:54:42 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 11:54:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 11:54:42 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 11:54:43 INFO - Return code: 0 11:54:43 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 11:54: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')]} 11:54:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.common.tests.zip 11:54:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:44 INFO - Downloaded 22573038 bytes. 11:54:44 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 11:54:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 11:54:44 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 11:54:45 INFO - Return code: 0 11:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:54:45 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 11:54:45 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:45 INFO - Downloaded 11131970 bytes. 11:54:45 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 11:54:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 11:54:45 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 11:54:45 INFO - Return code: 0 11:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:54:45 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.tar.bz2 11:54:45 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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 11:54:46 INFO - Downloaded 56620860 bytes. 11:54:46 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.tar.bz2 11:54:46 INFO - mkdir: /builds/slave/test-pgo/properties 11:54:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 11:54:46 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 11:54:46 INFO - Contents: 11:54:46 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.tar.bz2 11:54:46 INFO - Running post-action listener: _resource_record_post_action 11:54:46 INFO - Running post-action listener: set_extra_try_arguments 11:54:46 INFO - ##### 11:54:46 INFO - ##### Running populate-webroot step. 11:54:46 INFO - ##### 11:54:46 INFO - Running pre-action listener: _resource_record_pre_action 11:54:46 INFO - Running main action method: populate_webroot 11:54:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:54:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:54:46 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 11:54:46 INFO - '../fennec_ids.txt'], 11:54:46 INFO - 'tests': ['tcheck2']}, 11:54:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:54:46 INFO - '--tppagecycles', 11:54:46 INFO - '7'], 11:54:46 INFO - 'tests': ['tsvgm']}}, 11:54:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 11:54:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:54:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:54:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:54:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:54:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:54:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'tests': ['damp', 'tps']}, 11:54:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['damp', 'tps']}, 11:54:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 11:54:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:54:46 INFO - 'other': {'tests': ['a11yr', 11:54:46 INFO - 'ts_paint', 11:54:46 INFO - 'tpaint', 11:54:46 INFO - 'sessionrestore', 11:54:46 INFO - 'sessionrestore_no_auto_restore']}, 11:54:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['a11yr', 11:54:46 INFO - 'ts_paint', 11:54:46 INFO - 'tpaint', 11:54:46 INFO - 'sessionrestore', 11:54:46 INFO - 'sessionrestore_no_auto_restore']}, 11:54:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['a11yr', 11:54:46 INFO - 'ts_paint', 11:54:46 INFO - 'tpaint', 11:54:46 INFO - 'sessionrestore', 11:54:46 INFO - 'sessionrestore_no_auto_restore']}, 11:54:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['a11yr', 11:54:46 INFO - 'ts_paint', 11:54:46 INFO - 'tpaint', 11:54:46 INFO - 'sessionrestore', 11:54:46 INFO - 'sessionrestore_no_auto_restore']}, 11:54:46 INFO - 'other_l64': {'tests': ['a11yr', 11:54:46 INFO - 'ts_paint', 11:54:46 INFO - 'tpaint', 11:54:46 INFO - 'sessionrestore', 11:54:46 INFO - 'sessionrestore_no_auto_restore']}, 11:54:46 INFO - 'other_nol64': {'tests': ['a11yr', 11:54:46 INFO - 'ts_paint', 11:54:46 INFO - 'tpaint', 11:54:46 INFO - 'sessionrestore', 11:54:46 INFO - 'sessionrestore_no_auto_restore']}, 11:54:46 INFO - 'svgr': {'tests': ['tsvgx', 11:54:46 INFO - 'tsvgr_opacity', 11:54:46 INFO - 'tart', 11:54:46 INFO - 'tscrollx', 11:54:46 INFO - 'cart']}, 11:54:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['tsvgx', 11:54:46 INFO - 'tsvgr_opacity', 11:54:46 INFO - 'tart', 11:54:46 INFO - 'tscrollx', 11:54:46 INFO - 'cart']}, 11:54:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'tests': ['tp5o']}, 11:54:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'talos_options': ['--e10s'], 11:54:46 INFO - 'tests': ['tp5o']}, 11:54:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'talos_options': ['--xperf_path', 11:54:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:54:46 INFO - 'tests': ['tp5n']}, 11:54:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:54:46 INFO - 'talos_options': ['--e10s', 11:54:46 INFO - '--xperf_path', 11:54:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:54:46 INFO - 'tests': ['tp5n']}}, 11:54:46 INFO - 'talos.zip': {'path': '', 11:54:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:54:46 INFO - Running post-action listener: _resource_record_post_action 11:54:46 INFO - ##### 11:54:46 INFO - ##### Running create-virtualenv step. 11:54:46 INFO - ##### 11:54:46 INFO - Running pre-action listener: _resource_record_pre_action 11:54:46 INFO - Running main action method: create_virtualenv 11:54:46 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 11:54:46 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 11:54:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 11:54:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:54:46 INFO - Using real prefix '/usr' 11:54:46 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 11:54:47 INFO - Installing distribute.............................................................................................................................................................................................done. 11:54:49 INFO - Installing pip.................done. 11:54:49 INFO - Return code: 0 11:54:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 11:54:49 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')]} 11:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:49 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')]} 11:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:49 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:54:49 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 11:54:49 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 11:54:49 INFO - Using env: {'DISPLAY': ':0', 11:54:49 INFO - 'HOME': '/home/cltbld', 11:54:49 INFO - 'LANG': 'en_US.UTF-8', 11:54:49 INFO - 'LANGUAGE': 'en_US:en', 11:54:49 INFO - 'LOGNAME': 'cltbld', 11:54:49 INFO - 'MAIL': '/var/mail/cltbld', 11:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:49 INFO - 'MOZ_NO_REMOTE': '1', 11:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:49 INFO - 'NO_EM_RESTART': '1', 11:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:54:49 INFO - 'PWD': '/builds/slave/test-pgo', 11:54:49 INFO - 'SHELL': '/bin/bash', 11:54:49 INFO - 'SHLVL': '1', 11:54:49 INFO - 'TERM': 'linux', 11:54:49 INFO - 'TMOUT': '86400', 11:54:49 INFO - 'USER': 'cltbld', 11:54:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:49 INFO - '_': '/tools/buildbot/bin/python'} 11:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:49 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 11:54:49 INFO - Cleaning up... 11:54:49 INFO - Return code: 0 11:54:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 11:54:49 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')]} 11:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:49 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')]} 11:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:49 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:54:49 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 11:54:49 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 11:54:49 INFO - Using env: {'DISPLAY': ':0', 11:54:49 INFO - 'HOME': '/home/cltbld', 11:54:49 INFO - 'LANG': 'en_US.UTF-8', 11:54:49 INFO - 'LANGUAGE': 'en_US:en', 11:54:49 INFO - 'LOGNAME': 'cltbld', 11:54:49 INFO - 'MAIL': '/var/mail/cltbld', 11:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:49 INFO - 'MOZ_NO_REMOTE': '1', 11:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:49 INFO - 'NO_EM_RESTART': '1', 11:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:54:49 INFO - 'PWD': '/builds/slave/test-pgo', 11:54:49 INFO - 'SHELL': '/bin/bash', 11:54:49 INFO - 'SHLVL': '1', 11:54:49 INFO - 'TERM': 'linux', 11:54:49 INFO - 'TMOUT': '86400', 11:54:49 INFO - 'USER': 'cltbld', 11:54:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:49 INFO - '_': '/tools/buildbot/bin/python'} 11:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:49 INFO - Downloading/unpacking psutil>=0.7.1 11:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:51 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 11:54:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 11:54:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 11:54:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:54:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:54:51 INFO - Installing collected packages: psutil 11:54:51 INFO - Running setup.py install for psutil 11:54:51 INFO - building 'psutil._psutil_linux' extension 11:54:51 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 11:54:51 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 11:54:51 INFO - building 'psutil._psutil_posix' extension 11:54:51 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 11:54:51 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 11:54:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:54:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:54:51 INFO - Successfully installed psutil 11:54:51 INFO - Cleaning up... 11:54:51 INFO - Return code: 0 11:54:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 11:54:51 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')]} 11:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:51 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')]} 11:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:51 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:54:51 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 11:54:51 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 11:54:51 INFO - Using env: {'DISPLAY': ':0', 11:54:51 INFO - 'HOME': '/home/cltbld', 11:54:51 INFO - 'LANG': 'en_US.UTF-8', 11:54:51 INFO - 'LANGUAGE': 'en_US:en', 11:54:51 INFO - 'LOGNAME': 'cltbld', 11:54:51 INFO - 'MAIL': '/var/mail/cltbld', 11:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:51 INFO - 'MOZ_NO_REMOTE': '1', 11:54:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:51 INFO - 'NO_EM_RESTART': '1', 11:54:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:54:51 INFO - 'PWD': '/builds/slave/test-pgo', 11:54:51 INFO - 'SHELL': '/bin/bash', 11:54:51 INFO - 'SHLVL': '1', 11:54:51 INFO - 'TERM': 'linux', 11:54:51 INFO - 'TMOUT': '86400', 11:54:51 INFO - 'USER': 'cltbld', 11:54:51 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:51 INFO - '_': '/tools/buildbot/bin/python'} 11:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:54:52 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 11:54:52 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 11:54:52 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 11:54:52 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 11:54:52 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 11:54:52 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 11:54:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:54:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 11:54:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:54:53 INFO - Installing collected packages: mozsystemmonitor 11:54:53 INFO - Running setup.py install for mozsystemmonitor 11:54:53 INFO - Successfully installed mozsystemmonitor 11:54:53 INFO - Cleaning up... 11:54:54 INFO - Return code: 0 11:54:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 11:54:54 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')]} 11:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:54 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')]} 11:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:54 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:54:54 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 11:54:54 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 11:54:54 INFO - Using env: {'DISPLAY': ':0', 11:54:54 INFO - 'HOME': '/home/cltbld', 11:54:54 INFO - 'LANG': 'en_US.UTF-8', 11:54:54 INFO - 'LANGUAGE': 'en_US:en', 11:54:54 INFO - 'LOGNAME': 'cltbld', 11:54:54 INFO - 'MAIL': '/var/mail/cltbld', 11:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:54 INFO - 'MOZ_NO_REMOTE': '1', 11:54:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:54 INFO - 'NO_EM_RESTART': '1', 11:54:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:54:54 INFO - 'PWD': '/builds/slave/test-pgo', 11:54:54 INFO - 'SHELL': '/bin/bash', 11:54:54 INFO - 'SHLVL': '1', 11:54:54 INFO - 'TERM': 'linux', 11:54:54 INFO - 'TMOUT': '86400', 11:54:54 INFO - 'USER': 'cltbld', 11:54:54 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:54 INFO - '_': '/tools/buildbot/bin/python'} 11:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:54 INFO - Downloading/unpacking blobuploader==1.2.4 11:54:54 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 11:54:54 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 11:54:54 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 11:54:54 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 11:54:54 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 11:54:54 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 11:54:55 INFO - Downloading blobuploader-1.2.4.tar.gz 11:54:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 11:54:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:54:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:54:55 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 11:54:55 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 11:54:55 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 11:54:55 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 11:54:55 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 11:54:55 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 11:54:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 11:54:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 11:54:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:54:56 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 11:54:56 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 11:54:56 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 11:54:56 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 11:54:56 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 11:54:56 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 11:54:56 INFO - Downloading docopt-0.6.1.tar.gz 11:54:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 11:54:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 11:54:56 INFO - Installing collected packages: blobuploader, requests, docopt 11:54:56 INFO - Running setup.py install for blobuploader 11:54:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:54:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 11:54:56 INFO - Running setup.py install for requests 11:54:57 INFO - Running setup.py install for docopt 11:54:57 INFO - Successfully installed blobuploader requests docopt 11:54:57 INFO - Cleaning up... 11:54:57 INFO - Return code: 0 11:54:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:54:57 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')]} 11:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:57 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')]} 11:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:57 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:54:57 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 11:54:57 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 11:54:57 INFO - Using env: {'DISPLAY': ':0', 11:54:57 INFO - 'HOME': '/home/cltbld', 11:54:57 INFO - 'LANG': 'en_US.UTF-8', 11:54:57 INFO - 'LANGUAGE': 'en_US:en', 11:54:57 INFO - 'LOGNAME': 'cltbld', 11:54:57 INFO - 'MAIL': '/var/mail/cltbld', 11:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:57 INFO - 'MOZ_NO_REMOTE': '1', 11:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:57 INFO - 'NO_EM_RESTART': '1', 11:54:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:54:57 INFO - 'PWD': '/builds/slave/test-pgo', 11:54:57 INFO - 'SHELL': '/bin/bash', 11:54:57 INFO - 'SHLVL': '1', 11:54:57 INFO - 'TERM': 'linux', 11:54:57 INFO - 'TMOUT': '86400', 11:54:57 INFO - 'USER': 'cltbld', 11:54:57 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:57 INFO - '_': '/tools/buildbot/bin/python'} 11:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:54:57 INFO - Running setup.py (path:/tmp/pip-ofZJvV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:54:57 INFO - Running setup.py (path:/tmp/pip-2u0zrD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:54:57 INFO - Running setup.py (path:/tmp/pip-9SnpUf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:54:57 INFO - Running setup.py (path:/tmp/pip-UaDbHq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 11:54:57 INFO - Running setup.py (path:/tmp/pip-XZIMDw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:54:57 INFO - Running setup.py (path:/tmp/pip-Grp7dM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:54:57 INFO - Running setup.py (path:/tmp/pip-DKvHRj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:54:57 INFO - Running setup.py (path:/tmp/pip-mZxe8u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:54:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 11:54:57 INFO - Running setup.py (path:/tmp/pip-SEPwZj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 11:54:58 INFO - Running setup.py (path:/tmp/pip-EjMCo5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:54:58 INFO - Running setup.py (path:/tmp/pip-KcHHOB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:54:58 INFO - Running setup.py (path:/tmp/pip-AwVOYO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:54:58 INFO - Running setup.py (path:/tmp/pip-eDyTVC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:54:58 INFO - Running setup.py (path:/tmp/pip-yeXpd5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:54:58 INFO - Running setup.py (path:/tmp/pip-XfjRDM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 11:54:58 INFO - Running setup.py (path:/tmp/pip-296v5Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 11:54:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 11:54:58 INFO - Running setup.py (path:/tmp/pip-mNRwN7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 11:54:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:54:58 INFO - Running setup.py install for manifestparser 11:54:58 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 11:54:58 INFO - Running setup.py install for mozcrash 11:54:58 INFO - Running setup.py install for mozdebug 11:54:58 INFO - Running setup.py install for mozdevice 11:54:59 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Running setup.py install for mozfile 11:54:59 INFO - Running setup.py install for mozhttpd 11:54:59 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Running setup.py install for mozinfo 11:54:59 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Running setup.py install for mozInstall 11:54:59 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Running setup.py install for mozleak 11:54:59 INFO - Running setup.py install for mozlog 11:54:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Running setup.py install for moznetwork 11:54:59 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Running setup.py install for mozprocess 11:54:59 INFO - Running setup.py install for mozprofile 11:54:59 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 11:54:59 INFO - Running setup.py install for mozrunner 11:55:00 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 11:55:00 INFO - Running setup.py install for mozscreenshot 11:55:00 INFO - Running setup.py install for moztest 11:55:00 INFO - Running setup.py install for mozversion 11:55:00 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 11:55:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:55:00 INFO - Cleaning up... 11:55:00 INFO - Return code: 0 11:55:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:55: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')]} 11:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55: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')]} 11:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:00 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:55:00 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 11:55:00 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 11:55:00 INFO - Using env: {'DISPLAY': ':0', 11:55:00 INFO - 'HOME': '/home/cltbld', 11:55:00 INFO - 'LANG': 'en_US.UTF-8', 11:55:00 INFO - 'LANGUAGE': 'en_US:en', 11:55:00 INFO - 'LOGNAME': 'cltbld', 11:55:00 INFO - 'MAIL': '/var/mail/cltbld', 11:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:00 INFO - 'MOZ_NO_REMOTE': '1', 11:55:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:00 INFO - 'NO_EM_RESTART': '1', 11:55:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:55:00 INFO - 'PWD': '/builds/slave/test-pgo', 11:55:00 INFO - 'SHELL': '/bin/bash', 11:55:00 INFO - 'SHLVL': '1', 11:55:00 INFO - 'TERM': 'linux', 11:55:00 INFO - 'TMOUT': '86400', 11:55:00 INFO - 'USER': 'cltbld', 11:55:00 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:00 INFO - '_': '/tools/buildbot/bin/python'} 11:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:55:00 INFO - Running setup.py (path:/tmp/pip-LeiZIK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 11:55:00 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)) 11:55:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:55:00 INFO - Running setup.py (path:/tmp/pip-agD0M2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 11:55:00 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)) 11:55:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:55:00 INFO - Running setup.py (path:/tmp/pip-hhxwOG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 11:55:00 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)) 11:55:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:55:00 INFO - Running setup.py (path:/tmp/pip-1IEL5G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 11:55:00 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)) 11:55:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 11:55:00 INFO - Running setup.py (path:/tmp/pip-9XTAAv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 11:55:00 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)) 11:55:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:55:01 INFO - Running setup.py (path:/tmp/pip-xuvZqL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:55:01 INFO - Running setup.py (path:/tmp/pip-7PWZde-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:55:01 INFO - Running setup.py (path:/tmp/pip-nF2vzK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 11:55:01 INFO - Running setup.py (path:/tmp/pip-h6fkwF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 11:55:01 INFO - Running setup.py (path:/tmp/pip-B02sZo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:55:01 INFO - Running setup.py (path:/tmp/pip-VRTBCm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:55:01 INFO - Running setup.py (path:/tmp/pip-ezw14I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:55:01 INFO - Running setup.py (path:/tmp/pip-bIS0Co-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:55:01 INFO - Running setup.py (path:/tmp/pip-siBE7a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:55:01 INFO - Running setup.py (path:/tmp/pip-nK0Njm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 11:55:01 INFO - Running setup.py (path:/tmp/pip-O5noqr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 11:55:01 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)) 11:55:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 11:55:01 INFO - Running setup.py (path:/tmp/pip-WT6nrA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 11:55:01 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)) 11:55:01 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)) 11:55:01 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)) 11:55:01 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)) 11:55:01 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)) 11:55:01 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)) 11:55:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 11:55:01 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 11:55:01 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 11:55:01 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 11:55:01 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 11:55:01 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 11:55:01 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 11:55:03 INFO - Downloading blessings-1.5.1.tar.gz 11:55:03 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 11:55:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 11:55:03 INFO - Installing collected packages: blessings 11:55:03 INFO - Running setup.py install for blessings 11:55:03 INFO - Successfully installed blessings 11:55:03 INFO - Cleaning up... 11:55:03 INFO - Return code: 0 11:55:03 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 11:55:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 11:55:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 11:55:04 INFO - Reading from file tmpfile_stdout 11:55:04 INFO - Current package versions: 11:55:04 INFO - argparse == 1.2.1 11:55:04 INFO - blessings == 1.5.1 11:55:04 INFO - blobuploader == 1.2.4 11:55:04 INFO - docopt == 0.6.1 11:55:04 INFO - manifestparser == 1.1 11:55:04 INFO - mozInstall == 1.12 11:55:04 INFO - mozcrash == 0.16 11:55:04 INFO - mozdebug == 0.1 11:55:04 INFO - mozdevice == 0.46 11:55:04 INFO - mozfile == 1.2 11:55:04 INFO - mozhttpd == 0.7 11:55:04 INFO - mozinfo == 0.8 11:55:04 INFO - mozleak == 0.1 11:55:04 INFO - mozlog == 3.0 11:55:04 INFO - moznetwork == 0.27 11:55:04 INFO - mozprocess == 0.22 11:55:04 INFO - mozprofile == 0.27 11:55:04 INFO - mozrunner == 6.11 11:55:04 INFO - mozscreenshot == 0.1 11:55:04 INFO - mozsystemmonitor == 0.0 11:55:04 INFO - moztest == 0.7 11:55:04 INFO - mozversion == 1.4 11:55:04 INFO - psutil == 3.1.1 11:55:04 INFO - requests == 1.2.3 11:55:04 INFO - wsgiref == 0.1.2 11:55:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 11:55:04 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')]} 11:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:04 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')]} 11:55:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:04 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:55:04 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 11:55:04 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 11:55:04 INFO - Using env: {'DISPLAY': ':0', 11:55:04 INFO - 'HOME': '/home/cltbld', 11:55:04 INFO - 'LANG': 'en_US.UTF-8', 11:55:04 INFO - 'LANGUAGE': 'en_US:en', 11:55:04 INFO - 'LOGNAME': 'cltbld', 11:55:04 INFO - 'MAIL': '/var/mail/cltbld', 11:55:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:04 INFO - 'MOZ_NO_REMOTE': '1', 11:55:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:04 INFO - 'NO_EM_RESTART': '1', 11:55:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:55:04 INFO - 'PWD': '/builds/slave/test-pgo', 11:55:04 INFO - 'SHELL': '/bin/bash', 11:55:04 INFO - 'SHLVL': '1', 11:55:04 INFO - 'TERM': 'linux', 11:55:04 INFO - 'TMOUT': '86400', 11:55:04 INFO - 'USER': 'cltbld', 11:55:04 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:55:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:04 INFO - '_': '/tools/buildbot/bin/python'} 11:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 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)) 11:55:04 INFO - Cleaning up... 11:55:04 INFO - Return code: 0 11:55:04 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 11:55:04 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')]} 11:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:04 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')]} 11:55:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:04 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:55:04 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 11:55:04 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 11:55:04 INFO - Using env: {'DISPLAY': ':0', 11:55:04 INFO - 'HOME': '/home/cltbld', 11:55:04 INFO - 'LANG': 'en_US.UTF-8', 11:55:04 INFO - 'LANGUAGE': 'en_US:en', 11:55:04 INFO - 'LOGNAME': 'cltbld', 11:55:04 INFO - 'MAIL': '/var/mail/cltbld', 11:55:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:04 INFO - 'MOZ_NO_REMOTE': '1', 11:55:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:04 INFO - 'NO_EM_RESTART': '1', 11:55:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:55:04 INFO - 'PWD': '/builds/slave/test-pgo', 11:55:04 INFO - 'SHELL': '/bin/bash', 11:55:04 INFO - 'SHLVL': '1', 11:55:04 INFO - 'TERM': 'linux', 11:55:04 INFO - 'TMOUT': '86400', 11:55:04 INFO - 'USER': 'cltbld', 11:55:04 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:55:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:04 INFO - '_': '/tools/buildbot/bin/python'} 11:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:04 INFO - Downloading/unpacking jsonschema 11:55:04 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 11:55:04 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 11:55:04 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 11:55:04 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 11:55:04 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 11:55:04 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 11:55:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:55:06 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 11:55:06 INFO - Downloading/unpacking functools32 (from jsonschema) 11:55:06 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 11:55:06 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 11:55:06 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 11:55:06 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 11:55:06 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 11:55:06 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 11:55:06 INFO - Downloading functools32-3.2.3-2.tar.gz 11:55:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 11:55:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 11:55:06 INFO - warning: no files found matching '*.txt' 11:55:06 INFO - no previously-included directories found matching 'build' 11:55:06 INFO - no previously-included directories found matching 'dist' 11:55:06 INFO - no previously-included directories found matching '.git*' 11:55:06 INFO - Installing collected packages: jsonschema, functools32 11:55:06 INFO - Running setup.py install for functools32 11:55:06 INFO - warning: no files found matching '*.txt' 11:55:06 INFO - no previously-included directories found matching 'build' 11:55:06 INFO - no previously-included directories found matching 'dist' 11:55:06 INFO - no previously-included directories found matching '.git*' 11:55:06 INFO - Successfully installed jsonschema functools32 11:55:06 INFO - Cleaning up... 11:55:06 INFO - Return code: 0 11:55:06 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 11:55:06 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')]} 11:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:06 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')]} 11:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:55:06 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 0x7f1d2e2e9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b37600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', '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 11:55:06 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 11:55:06 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 11:55:06 INFO - Using env: {'DISPLAY': ':0', 11:55:06 INFO - 'HOME': '/home/cltbld', 11:55:06 INFO - 'LANG': 'en_US.UTF-8', 11:55:06 INFO - 'LANGUAGE': 'en_US:en', 11:55:06 INFO - 'LOGNAME': 'cltbld', 11:55:06 INFO - 'MAIL': '/var/mail/cltbld', 11:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:06 INFO - 'MOZ_NO_REMOTE': '1', 11:55:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:06 INFO - 'NO_EM_RESTART': '1', 11:55:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:55:06 INFO - 'PWD': '/builds/slave/test-pgo', 11:55:06 INFO - 'SHELL': '/bin/bash', 11:55:06 INFO - 'SHLVL': '1', 11:55:06 INFO - 'TERM': 'linux', 11:55:06 INFO - 'TMOUT': '86400', 11:55:06 INFO - 'USER': 'cltbld', 11:55:06 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:06 INFO - '_': '/tools/buildbot/bin/python'} 11:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:55:06 INFO - Downloading/unpacking flake8 11:55:06 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 11:55:06 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 11:55:06 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 11:55:06 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 11:55:06 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 11:55:06 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 11:55:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:55:08 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 11:55:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:55:08 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 11:55:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:08 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 11:55:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 11:55:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 11:55:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:55:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:55:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:55:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:55:09 INFO - no previously-included directories found matching 'docs/_build' 11:55:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:55:09 INFO - Running setup.py install for pep8 11:55:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:55:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:55:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:55:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:55:09 INFO - no previously-included directories found matching 'docs/_build' 11:55:09 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 11:55:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:55:09 INFO - Cleaning up... 11:55:09 INFO - Return code: 0 11:55:09 INFO - Running post-action listener: _resource_record_post_action 11:55:09 INFO - Running post-action listener: _start_resource_monitoring 11:55:09 INFO - Starting resource monitoring. 11:55:09 INFO - ##### 11:55:09 INFO - ##### Running install step. 11:55:09 INFO - ##### 11:55:09 INFO - Running pre-action listener: _resource_record_pre_action 11:55:09 INFO - Running main action method: install 11:55:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 11:55:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 11:55:09 INFO - Reading from file tmpfile_stdout 11:55:09 INFO - Detecting whether we're running mozinstall >=1.0... 11:55:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 11:55:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 11:55:09 INFO - Reading from file tmpfile_stdout 11:55:09 INFO - Output received: 11:55:09 INFO - Usage: mozinstall [options] installer 11:55:09 INFO - Options: 11:55:09 INFO - -h, --help show this help message and exit 11:55:09 INFO - -d DEST, --destination=DEST 11:55:09 INFO - Directory to install application into. [default: 11:55:09 INFO - "/builds/slave/test-pgo"] 11:55:09 INFO - --app=APP Application being installed. [default: firefox] 11:55:09 INFO - mkdir: /builds/slave/test-pgo/build/application 11:55:09 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'] 11:55:09 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 11:55:23 INFO - Reading from file tmpfile_stdout 11:55:23 INFO - Output received: 11:55:23 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 11:55:24 INFO - Running post-action listener: _resource_record_post_action 11:55:24 INFO - ##### 11:55:24 INFO - ##### Running run-tests step. 11:55:24 INFO - ##### 11:55:24 INFO - Running pre-action listener: _resource_record_pre_action 11:55:24 INFO - Running main action method: run_tests 11:55:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 11:55:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 11:55:24 INFO - Python 2.7.3 11:55:24 INFO - Return code: 0 11:55:24 INFO - grabbing minidump binary from tooltool 11:55:24 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')]} 11:55:24 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 0x2bc6350>, '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 0x2bc7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc76e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 11:55:24 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 11:55:24 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 11:55:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:55:24 INFO - Return code: 0 11:55:24 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 11:55:24 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 11:55:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 11:55:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 11:55:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 11:55:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 11:55:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 11:55:25 INFO - Return code: 0 11:55:25 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/1451928578/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 11:55:25 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/1451928578/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost 11:55:25 INFO - Using env: {'DISPLAY': ':0', 11:55:25 INFO - 'HOME': '/home/cltbld', 11:55:25 INFO - 'LANG': 'en_US.UTF-8', 11:55:25 INFO - 'LANGUAGE': 'en_US:en', 11:55:25 INFO - 'LOGNAME': 'cltbld', 11:55:25 INFO - 'MAIL': '/var/mail/cltbld', 11:55:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 11:55:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 11:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:55:25 INFO - 'MOZ_NO_REMOTE': '1', 11:55:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 11:55:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:55:25 INFO - 'NO_EM_RESTART': '1', 11:55:25 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 11:55:25 INFO - 'PWD': '/builds/slave/test-pgo', 11:55:25 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 11:55:25 INFO - 'SHELL': '/bin/bash', 11:55:25 INFO - 'SHLVL': '1', 11:55:25 INFO - 'TERM': 'linux', 11:55:25 INFO - 'TMOUT': '86400', 11:55:25 INFO - 'USER': 'cltbld', 11:55:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187', 11:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:55:25 INFO - '_': '/tools/buildbot/bin/python'} 11:55:25 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/1451928578/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost'] with output_timeout 3600 11:55:25 INFO - mozversion INFO | application_buildid: 20160104092938 11:55:25 INFO - mozversion INFO | application_changeset: 0b7ba87369066dc4401dac3a8ab81ca0cb366993 11:55:25 INFO - mozversion INFO | application_display_name: Nightly 11:55:25 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:55:25 INFO - mozversion INFO | application_name: Firefox 11:55:25 INFO - mozversion INFO | application_remotingname: firefox 11:55:25 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:55:25 INFO - mozversion INFO | application_vendor: Mozilla 11:55:25 INFO - mozversion INFO | application_version: 44.0 11:55:25 INFO - mozversion INFO | platform_buildid: 20160104092938 11:55:25 INFO - mozversion INFO | platform_changeset: 0b7ba87369066dc4401dac3a8ab81ca0cb366993 11:55:25 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:55:25 INFO - mozversion INFO | platform_version: 44.0 11:55:25 INFO - 2016-01-04 11:55:25,217 DEBUG : using testdate: 1451937325 11:55:25 INFO - 2016-01-04 11:55:25,217 DEBUG : actual date: 1451937325 11:55:25 INFO - 2016-01-04 11:55:25,225 INFO : starting webserver on 'localhost:38748' 11:55:25 INFO - 2016-01-04 11:55:25,226 INFO : Starting test suite talos-linux64-ix-006 11:55:25 INFO - 2016-01-04 11:55:25,226 INFO : Starting test dromaeo_css 11:55:25 INFO - 2016-01-04 11:55:25,227 DEBUG : operating with platform_type : linux_ 11:55:25 INFO - 2016-01-04 11:55:25,227 INFO : Initialising browser for dromaeo_css test... 11:55:25 INFO - 2016-01-04 11:55:25,240 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzkYf9Z/profile http://localhost:38748/getInfo.html 11:55:28 INFO - 2016-01-04 11:55:28,658 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 11:55:28 INFO - 2016-01-04 11:55:28,659 DEBUG : BROWSER_OUTPUT: colorDepth:24 11:55:28 INFO - 2016-01-04 11:55:28,659 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 11:55:28 INFO - 2016-01-04 11:55:28,659 DEBUG : BROWSER_OUTPUT: __metrics 11:55:29 INFO - 2016-01-04 11:55:29,206 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] 11:55:30 INFO - 2016-01-04 11:55:30,174 INFO : Browser initialized. 11:55:30 INFO - 2016-01-04 11:55:30,175 INFO : Running cycle 1/1 for dromaeo_css test... 11:55:30 INFO - 2016-01-04 11:55:30,175 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzkYf9Z/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 11:55:41 INFO - 2016-01-04 11:55:41,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 128999424 11:55:41 INFO - 2016-01-04 11:55:41,701 DEBUG : BROWSER_OUTPUT: 11:56:32 INFO - 2016-01-04 11:56:32,424 DEBUG : BROWSER_OUTPUT: 1451937392423 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] 11:58:40 INFO - 2016-01-04 11:58:40,410 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38748/tests/dromaeo/cssquery-ext.html) 11:58:41 INFO - 2016-01-04 11:58:41,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 244269056 11:58:41 INFO - 2016-01-04 11:58:41,534 DEBUG : BROWSER_OUTPUT: 12:01:43 INFO - 2016-01-04 12:01:43,659 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/dromaeo/cssquery-ext.html (next: http://localhost:38748/tests/dromaeo/cssquery-jquery.html) 12:01:44 INFO - 2016-01-04 12:01:44,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 209223680 12:01:44 INFO - 2016-01-04 12:01:44,221 DEBUG : BROWSER_OUTPUT: 12:04:45 INFO - 2016-01-04 12:04:45,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38748/tests/dromaeo/cssquery-mootools.html) 12:04:46 INFO - 2016-01-04 12:04:46,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 205615104 12:04:46 INFO - 2016-01-04 12:04:46,515 DEBUG : BROWSER_OUTPUT: 12:07:48 INFO - 2016-01-04 12:07:48,239 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38748/tests/dromaeo/cssquery-prototype.html) 12:07:48 INFO - 2016-01-04 12:07:48,802 DEBUG : BROWSER_OUTPUT: RSS: Main: 192266240 12:07:48 INFO - 2016-01-04 12:07:48,802 DEBUG : BROWSER_OUTPUT: 12:10:51 INFO - 2016-01-04 12:10:51,113 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38748/tests/dromaeo/cssquery-yui.html) 12:10:51 INFO - 2016-01-04 12:10:51,675 DEBUG : BROWSER_OUTPUT: RSS: Main: 195866624 12:10:51 INFO - 2016-01-04 12:10:51,676 DEBUG : BROWSER_OUTPUT: 12:13:53 INFO - 2016-01-04 12:13:53,764 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/dromaeo/cssquery-yui.html (next: http://localhost:38748/tests/dromaeo/cssquery-dojo.html) 12:13:54 INFO - 2016-01-04 12:13:54,007 DEBUG : BROWSER_OUTPUT: RSS: Main: 230547456 12:13:54 INFO - 2016-01-04 12:13:54,008 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,009 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:13:54 INFO - 2016-01-04 12:13:54,009 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:13:54 INFO - 2016-01-04 12:13:54,009 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:13:54 INFO - 2016-01-04 12:13:54,009 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:13:54 INFO - 2016-01-04 12:13:54,011 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3266.2814180880205;3332.483465062913;3360.075979105747;3368.6968172864435;3373.3927892979254;5576.052071147905;5603.887922241553;5608.298962629671;5634.774609015634;5638.267025286712;5572.0527510323245;5609.775608975647;5621.184928185411;5626.043572592659;5635.239242702234;4959.727215003173;5090.821821236248;5119;5197.532222100011;5236.554892834114;5146.691198528081;5163.535281824635;5165.535101840834;5167.147420675584;5174.715390653512;5047.67190132642;5078.111330517163;5098.566621837148;5154.278401023859;5186.636935414523;5078.289039534449;5079.644424890259;5082.390113186441;5084.593232541388;5100.132977393852;5049.949500504985;5056.544910958031;5102.974485127587;5109.3613298337705;5166.328377310963;5283.656562323436;5373.167159090347;5385.946140538584;5493.725313734337;5512.5589952803675;6013.669248191348;6056.454919057306;6060.696965151725;6067.969660151671;6083.48290395317;5921.319048309413;6113.113598528189;6117.84705382369;6123.755049798047;6134.2332208473945;5583.162525621149;5621.297337832771;5699.800506982236;5701.543876489913;5722.856928576777;6018.939810601925;6020.337762846083;6023.186869772612;6044.365341639064;6175;5721.685307308055;5833.620814647119;5848.502877255481;5855.94144058554;5881.617694849821;5998;6064.666443345658;6107.725152368154;6126.081087836771;6141.692915354214;6037.939620603738;6060.2424696912885;6062.181605483203;6067.969660151671;6077.878442431215;5772.538196944234;5861.325947516005;5888;6052.606580572248;6071.483923866521;4500.099980003947;4501.369808226851;4505.053938672915;4505.797239124247;4507;4031.8387264509092;4115.36211887158;4128.60778226068;4143.0678097427835;4154.293770059036;5741.5119714824705;5750.281214848144;5775.768969241183;5790.971045144832;5821.27234095738;5814.534837213013;5925.9407405925385;6119.418655227746;6124.601900876429;6142.539309551801;5683.8863222736145;5750.683712395775;5761.423857614205;5762.481376676119;5793.333766616893;5260.658057226268;5266.7629956652045;5277.656952298088;5289.656172348785;5291.3385826771655;5959.970200149059;6059.394060593993;6120.479759220516;6205.348438413992;6221.222347206599;6082.513398928164;6088.238970128734;6100.115483254992;6100.725467353891;6129.141920131096;6236.158118654105;6248.312685604671;6269.404406581368;6271.184745982993;6283.748650053946;5789.623674461147;5885.47030767233;5892.35184129754;5983.311919129356;6006.729697163551;6081.996470582304;6082.026875699867;6086.69566521681;6093.969530152321;6099.085137229451;5920.200772895776;5970.432808883149;5970.880582388415;5976.760929562943;5982.252218472691;5822.854428639318;5844.064949608042;5922.496587789914;5928.081147422156;5943.702814859326;5785.479306862402;5833.241678581757;5838.912416313844;5839.153322768259;5877.147813566923;5766.509846663081;5951.821445356646;5957.582969192115;5978.9402105980125;5992.730327135202;5730.598858080061;5765.769369225184;5840.036393995113;5843.152742852178;5847.707614619167 12:13:54 INFO - 2016-01-04 12:13:54,014 DEBUG : BROWSER_OUTPUT: |1;ext.html;576.3084298841392;612.6079309242083;624.2508989212944;628.6196850905202;631.2929518938787;34664.13339666509;43192.13615727687;43806.78096609517;43849.56150438495;43860.34209486852;45876.770616146954;46788.53211467884;46815.76592117034;46825.76587117068;46868.53131468693;8008;8134.633941472648;9097.454152750839;9298.116678915492;10706.357618542874;10810.135189184886;10920.617778377758;10952.945235273852;10960.671179864617;10965.396903170322;10885.673429797118;10940.3982780947;10952.671419857417;10954.780904381907;10962.616308429206;10745;10885.510152043176;10962;10973.177011724152;10987.39569323687;10731.892681073168;10873.293235939718;10883.510242039049;10888.074513666348;10889.128869690407;43405.565944340786;43735.562644373786;43761.343579846325;43837.56162438367;43852;3617.186133120027;3707.0361705956648;4038.8182531785847;4302.741835489881;4376.190404775127;32645.020649380556;32745;32769.344613107845;32824.34351312961;32847.67152328494;3773.9056523586855;3821.426785982125;3869.090763670535;3966.5438474575503;3978.264021156095;306390;307516.46241768874;308299;309833;318466;64813;66165.33834661591;66908;73170;75511.62244188854;67181;67583.32416675868;67701.3229867705;67819.32180678229;67821.3217867815;17890.731639025165;18003.819961800476;18025.63948721018;18036.90981545084;18052.54868628295;13697.315134243248;13784.862151378558;13794.65513362174;13802.447902084004;13977.301134943416;21124.683129753066;21327.253546125812;21353.14587416486;21372;21384.78615213859;8713.477191368538;8737.519436431057;8770.605322760492;8803.163699448542;8997.955010224907;1038.0864838941684;1144.9084073274119;1156.8033434316187;1182.148852825964;1236.567201479493;30550.847245764075;30746.923857665322;30849;31621.525677114863;31756.682433175836;1082.377632861108;1111.344306858957;1185.6561597136733;1242.9005679545796;1264.9114561980573;13597.796033059512;16233.675326493403;16311.673766524604;16624.3350265988;16630.916845415697;364537;368834;369909;370160.0443984502;370785;50698.746506267235;51640.225396619084;57362.42637573571;57716.711416442646;58840.411595883495;789.2242454477425;1292.237579827906;1473.5579326202096;1495.5513345996158;1496.5809573319489;30192;30904.381912362078;30930.845345773127;31601;31710.524342134424;50824;51251.2312315323;51279.743601281756;51702.741486292325;51860;322545;325498;325866;326051;326857;35632.28735425329;36086.45870311895;36246.818765906;36343.454848177826;36359.81820090883;47398.526014739415;48543;48794.26808597803;48921;49527.75236123797;38959.41560876646;39761.60238397695;39973.40039899462;40708.59291407166;41040.384394233515;38480.23039539249;38965;39304.21391572095;40441.39337909993;40698;40392.596074038884;40408;40539;40642.79678601588;41128.58871411248;32692;32997.34005319928;35052.82473587616;35787.28425431425;36167.638323616426;25545.48909021772;27135.45729085368;27437.86281068582;27477.862610686818;27533.586996195474 12:13:54 INFO - 2016-01-04 12:13:54,016 DEBUG : BROWSER_OUTPUT: |2;jquery.html;382.22408510723244;382.61929380266605;385.20839674468954;386.27958856732187;388.6638058079761;12552.81170782439;15061.698766024672;17998.920064796104;19494.90252548737;19498.220071197153;18542.814571854276;18679.81320186801;18701.34545290915;19137.71293430596;19481.805181948177;5648.322201335835;5761.769529218826;5765.2216950711745;5783.884322313551;5787.334456537489;4404.4934832494355;4513.14250292445;5737.340205876315;6259.749610015594;6434.742610295582;6337.8415539611415;6426.967865160691;6455.870882582346;6458.709358078874;6483.578567393128;6448.742050317982;6459.128017717619;6487.18910136233;6493.545451818374;6495.480361571086;4420.889477763081;4496.392986946753;5782.306123265193;5785.739641716133;5798.217240672498;14897.627559310995;14898.180600066991;14948.925255373653;15145.091294522254;15342;2183.3995651195887;2194.5501172259646;2262.332611879483;2301.2175860207444;2324.8140148788057;10929.91186521976;11259.099272058216;11262.54949802007;11295.49170287339;11372.260803047775;1870.2425517665367;1884.076802366844;1885.764279465067;1886.368066697658;1900.4583693647244;68678.31321686819;69714.65142674305;70447.64776176137;70821.64589177021;71091.28908710899;7863.764087077397;7881.921180788233;7924.683012679428;7956.602169891482;7984.800379990546;9492;9531.523423828781;9544.761380965392;9568.330216884751;9574.66488672907;6375.171227740364;6424.100625912376;6484.1246431731115;6508;6517.120188774549;6730.899036514456;6741.426978706865;6745.325467453314;6780.796576102724;6817.181938167451;3648.635136486383;3683.8158092095287;3687.3362794697214;3693.981530092386;3709.4621279914263;1261.482792055253;1271.1483306184878;1276.7765641012786;1287.76820172368;1321.9801702974464;487.7000644603543;525.4535283305396;533.3280067115462;540.2193829915734;543.2204786131913;11512.76974460506;13141.211527308393;13520.12119212248;13690.178589284675;13752.587422377344;549.4340828946192;552.9060059789846;555.8054680861746;555.8165805284247;563.6223730100842;6999.0551275577145;7110.431165506747;7258.818529536698;7284.162321333115;7294.197638259893;614282;617681;636978;641175;647495;73831.63084184544;74719.62640186764;86680.56659716662;87812.12187878168;87995;568.4145330309788;582.2488989203964;583.270911360799;583.3903570768557;583.6089819820645;11821.82267266009;11916.165868389477;12030.15788894769;12058.095642826647;12080.697982550506;35002.29995400027;35080.649193507736;35369.64630353663;36258.093547660465;38238.235235295695;52249.47750522598;52939.205911912126;53852.46147538631;54319.45680543144;54466;7360.852782944204;7371.520851144873;7553.7356192533;7631.69473221065;7648.311651951351;11477.36874471912;11502;13081;13419.396127174105;13437.86562134366;10368.377897326185;11580.768384632092;11804.527818887149;11905.940470297593;11938.522459101887;11471.08231341474;11528.135389845962;11565.190436669689;11721.296722196696;11749.47127379253;11506.827397588875;11641.41792910368;11994.880051199376;12365.814512782135;12516.624501264976;7641.350485208597;7687.461877668509;7743.419243556868;7767.650455729393;7783.299503044753;12133.575324863587;12197;12227.266364018187;12309.076819238413;12418.37908104573 12:13:54 INFO - 2016-01-04 12:13:54,019 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1522.6345677037507;1527.1829571179414;1762.383165891937;1818.6999145141049;1850.9259629614821;7345.8163545911375;7534.3972482201425;7965.522068675877;8409.957950210248;8420.873686894694;7891.447598668095;8106.270435660789;8107.594620268977;8108.067572229195;8117.066537348207;2301.850379725321;2353.7763912428377;2367.159658321297;2367.3844800351862;2371.5019845832394;2053.6303465376227;2115.122224276923;2338.5089131282366;2342.8594284342907;2346.9647955280684;2372.9762702372927;2409.8674572898485;2413.3001429585397;2413.348395933097;2413.6741539892064;2319.512902290521;2343.308723926446;2358.752331005237;2358.9882050589636;2361.7284012338628;2350.5416443793465;2358.469344397514;2375.0143690368477;2377.1561095811085;2377.667126602277;8142.104368519458;8207.261346478786;8209.13804050572;8220.876686849702;8228.30059444942;4810.879728006793;4870.537298956593;4878.0975519529;4878.585320247783;4880.731559764212;3237;3253.6258330292085;3289.506574013917;3292.522584225298;3298.6041674999155;1004.1615251265202;1023.0792286941737;1024.303473637926;1099.5162128663362;1111.5331560744426;11140.610078647209;11153;11153.609623663133;11159.665210043711;11188.888111118948;4382.496012958487;4386.2762644163995;4415.536368681306;4427.048184640318;4434.866953991385;9453.243740500744;9464.337496375188;9484.668036618685;9501.762455938657;9515.57179926905;4947.826826061142;4956.851294461172;4960.975195124001;4962.057209130254;4962.429320628102;4532.9093418132115;4535.523770004168;4542.727436353829;4545.727256364628;4547.045120524661;3822.4075268333454;3897.512810898638;3899.6880249579967;3917.0794863207125;3931.901702457404;1116.5310569560804;1224.210384302138;1231.8275441438207;1237.3998610673816;1272.1222356573935;1134;1136.5510623303915;1141.383652827464;1166.4867458318313;1201.7416255504934;7497.962510187414;7584.734534291273;7596.354309883612;7629.656665450068;7639.847203055908;1113.821788513834;1148.242160174281;1186.8694443611196;1207.3178654060428;1226.178460431513;6323.367663233732;6328.71520781566;6329.461995730415;6381.298057213703;6382.361763823674;9243;9432.056794320513;9441.622335106656;9446.197073248713;9455.243580513543;9323.953380233192;9336.299777516713;9342.766430839283;9374.67188648394;9374.85937710935;7532.246775322534;7664.885026724603;7666.846663066596;7680.5391676499585;7690.346320562703;7521.623918804147;7536.020317358708;7546.698132074655;7571.053618297713;7579;8617.741467755977;8760.824783504167;8838.29293656493;8864.290856731317;8895.555222238992;8659.740207793777;8783.209511144029;9045.864312035194;9049.638014479611;9068.274538037072;7362.595057271901;7422.257774222535;7432.2567743225245;7433.145188303414;7440.330370266702;7038.753643622654;7580.279873412017;7580.658870350737;7856.292933636;7874.921250787648;6862.656867156722;7158.391536719437;7442.665080071301;7533.20901305377;7560.130584982798;7239.203687594466;7307.8538429232185;7322;7349.522281051714;7361.595112268877;7594.468387212842;7657.6554055066545;7661.961690191513;7668.041494813148;7669.309762121436;6924.307569243036;6993.230744617987;7043.049188359506;7043.2604576518725;7048.295170482911;6984.511084224056;7023.086998690138;7025.718971241093;7029;7029.929700703132 12:13:54 INFO - 2016-01-04 12:13:54,021 DEBUG : BROWSER_OUTPUT: |4;prototype.html;336.6280260312355;345.5456075261031;355.5520044743624;361.0414349901014;363.39494741255794;5858.472737453629;5893.8821223575505;5935.76256949722;5939.168516407701;5941.6435013899145;5760.45275698809;5802.854928626786;5861.706914654272;5887.264091988502;5894.056950887859;4605.401297831278;4619.053094115707;4629.097326021427;4634.37435946148;4641.744704041276;4618.076384723052;4621.653375996797;4621.74580398078;4622.098690755304;4627.467841198271;4489.438820147481;4581.335706322581;4614.054118905633;4633.4439867215815;4691.71849689018;4644.837430689943;4655.092257009885;4673.83641572545;4685.976570117145;4693.882652933687;3976.1053762903402;4144.5026596808575;4155.23128221277;4156.875293741193;4166.062635906927;5510.6969116698565;5522.22688823565;5623.803166889139;5625.184348269478;5651.8304450866535;5303.8408847734245;5312;5317.229001794757;5318.388385335697;5322.7072511011875;6246.718897649616;6247.375262473716;6248.31268560458;6249.687515624246;6255.374462553754;4382;4433.733975961452;4440.267355886275;4458.398116254297;4515.729056256635;6400.679966001681;6453.935460645381;6459.741610335628;6461.0631458438265;6477.222733272038;4510.015593139008;4512.007358381151;4514.480834704051;4515.796789144496;4517.728936263835;4579.816807327669;4586.977065114653;4587.495375508692;4593.333966574828;4600;5165.225216217522;5255.264263003182;5265.578753699695;5279.340082489689;5280.841574752763;6379.808605741742;6382.4255816975765;6386.074019267273;6389.041643753381;6391.648459334781;1652.5868532866784;1669.2404955745108;1670.5573023148875;1671.2980548169799;1673.029642807169;1121.5738019552518;1125.0605744203517;1130.8473356096822;1141.658551202334;1151.067635215478;6183.350748171467;6188.752449901954;6193.659348735863;6197.101420294122;6205.844853878689;6577.375149360803;6580.76967306142;6584.078229047937;6585.2426970898005;6592.736290548421;6074.696265186723;6185.474234690102;6187.566870319034;6190.350013248634;6198.411150940653;3612.205314830733;3617.8914632561064;3642.3990041642833;3643.2167084076523;3645.653662902073;6504.609723416515;6595;6632.900506492407;6745.325467453314;6750.3924646782025;6620.602763834186;6628.370304821035;6652.334766523406;6673.966630166915;6678.53250272486;6697.732090716317;6700.698468568926;6701.195856497154;6709.396154346035;6710.83222919431;6351.650659213788;6512.544121911421;6519.804405867641;6521.67391630426;6530.8367290818105;6521.9564869620635;6522.380373864476;6524.314946930694;6527.934720652733;6528.67356632176;6812.965935170292;6815.079964204769;6816.693248803915;6819.522633415613;6820.113385259884;4481.282994720829;4596.034832685174;4605.93091103627;4613.0773845230415;4616.653750968623;6630.83422914431;6633.70148343316;6636.435902948304;6636.966815165893;6642.701078451385;6437.066625339393;6452.9677351612945;6457.289698133295;6462.773802916875;6471.676416179078;6352.237731472253;6423.871522569616;6445.806625801233;6445.935540644534;6459.870802584016;6311.715972781144;6318.652474113968;6318.6840657965995;6319.52603554726;6321.956877115408;6487;6489.675516224264;6490.091387205889;6491.967540162269;6499.28507864144;6175.536834737323;6263.718132683949;6266.091416744638;6266.905996410149;6274.623522588659 12:13:54 INFO - 2016-01-04 12:13:54,022 DEBUG : BROWSER_OUTPUT: |5;yui.html;820.6799348254181;904.9909500904989;910.3809409601471;911.1981456318434;919.6919032124238;14121.929390353047;14393.424263029492;14398.856011439895;14407.351669174886;14568.344424500896;12703.745925081494;12878.549250776225;12898;12914.35428228587;12918.03114766394;746.936510396617;750.6772088002157;886.6586364249746;893.7944272817256;898.1557336104074;887.6715615222377;891.545311890937;892.7143314139446;900.6172376739892;900.6397441023611;935.4013431403907;940.8306504829128;941.7033634405141;942.1614762861059;942.2650332740474;777.8249893773884;783.2441693765516;933.2254228989956;936.976575585609;940.9482478463682;702.1083224305155;702.9753958611424;703.5391818358983;703.9929600703979;704.7075463682565;13575.049746517747;13691.726165476635;13736.450541978309;13763.862361376358;13774.517891873737;13588.25264610446;14340.42638294467;14445.205513696743;14463;14476.927615361961;11180.832287515694;11629.534818607246;11633.825492617532;11836.349000804952;11999;3523.9119022024397;3533.1873669056263;3555.4311310190506;3574.088607405121;3632.037510059836;45278;46566.301505477444;46570.76714616439;46963.76518117422;47073.29390059152;8389.454685445426;8397.95801020991;8399.160083991552;8419.74740757766;8553.187447192508;13226.603201903958;13255.403506842214;13265.933670331586;13275.269860157785;13279.601611951657;7007.334303241184;7015.8596828063155;7102.715891364288;7234.674439650228;7254.673539690621;4690.7654617269;4739.431268247763;4760.738159401266;4763.928541071887;4836.637252206098;2947.64628244612;2960.718731720483;2968.569557414163;2969.792114551961;2969.866356013941;480.6371189751715;484.3461327208241;492.9802807887716;495.37087898368867;500.74712270303263;606.4693393280879;642.4154019841922;699.3006993006993;701.193627328566;713.8144082538473;9024.819503610022;9302.488363139957;9815.656452024145;9929.30494865367;9969.252306077073;723.64541374726;749.2133260076897;752.6763491698622;778.4628606261662;780.621398621668;4279.2083464559155;4441.451552562664;4443.688941774045;4447.510773814878;4503.504614492404;67802.66098669475;68174.31825681779;68549.65725171342;68645.3135468639;68760.3123968764;57143;57576.71211643915;57766.71116644474;57979.13031304534;57997.71001144967;53119;53798.731006344715;54137.18794218011;54312.72843635757;54776.72611636996;8750.299976001777;8857.557122143997;9011.819763604823;9113.635454581743;9250.444973301623;38490.615093848704;39033.59420116707;39272;39438;39882.20235595214;47492.2876156851;52230;52745.47254527405;53462.73268633632;53544;7886.171951944963;8330.666773329;8376.623051962799;8393.790155246168;8476.745697629081;12610.49558017669;12768.6807829805;12935.870641293466;12950.481980721042;13096.607101786962;12004.639860804189;12191.75616487683;12261.69345766363;12321.630351089481;12405.689857753267;11688.824667629822;11959.820602691141;12389;12426.875731242571;13122.343882805631;10104.595816167272;12340.81488777651;12375.504979800708;12516.31160286193;12626.810597841224;10724.517396717323;11024.669259922215;11044.282121662067;11127.944360278147;11244.212905096565;10326.277160598685;10334.379937203792;10580.15358771312;10790;11050.834237486284 12:13:54 INFO - 2016-01-04 12:13:54,022 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:13:54 INFO - 2016-01-04 12:13:54,022 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:13:54 INFO - 2016-01-04 12:13:54,022 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,864 12:13:54 INFO - 2016-01-04 12:13:54,022 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: __startTimestamp1451938434008__endTimestamp 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5596.07 Median:5821.27 stddev:633.11 (10.9%) stddev-sans-first:608.09 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: Values: 3266.3 3332.5 3360.1 3368.7 3373.4 5576.1 5603.9 5608.3 5634.8 5638.3 5572.1 5609.8 5621.2 5626.0 5635.2 4959.7 5090.8 5119.0 5197.5 5236.6 5146.7 5163.5 5165.5 5167.1 5174.7 5047.7 5078.1 5098.6 5154.3 5186.6 5078.3 5079.6 5082.4 5084.6 5100.1 5049.9 5056.5 5103.0 5109.4 5166.3 5283.7 5373.2 5385.9 5493.7 5512.6 6013.7 6056.5 6060.7 6068.0 6083.5 5921.3 6113.1 6117.8 6123.8 6134.2 5583.2 5621.3 5699.8 5701.5 5722.9 6018.9 6020.3 6023.2 6044.4 6175.0 5721.7 5833.6 5848.5 5855.9 5881.6 5998.0 6064.7 6107.7 6126.1 6141.7 6037.9 6060.2 6062.2 6068.0 6077.9 5772.5 5861.3 5888.0 6052.6 6071.5 4500.1 4501.4 4505.1 4505.8 4507.0 4031.8 4115.4 4128.6 4143.1 4154.3 5741.5 5750.3 5775.8 5791.0 5821.3 5814.5 5925.9 6119.4 6124.6 6142.5 5683.9 5750.7 5761.4 5762.5 5793.3 5260.7 5266.8 5277.7 5289.7 5291.3 5960.0 6059.4 6120.5 6205.3 6221.2 6082.5 6088.2 6100.1 6100.7 6129.1 6236.2 6248.3 6269.4 6271.2 6283.7 5789.6 5885.5 5892.4 5983.3 6006.7 6082.0 6082.0 6086.7 6094.0 6099.1 5920.2 5970.4 5970.9 5976.8 5982.3 5822.9 5844.1 5922.5 5928.1 5943.7 5785.5 5833.2 5838.9 5839.2 5877.1 5766.5 5951.8 5957.6 5978.9 5992.7 5730.6 5765.8 5840.0 5843.2 5847.7 12:13:54 INFO - 2016-01-04 12:13:54,023 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,024 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:52341.62 Median:35936.87 stddev:87763.35 (244.2%) stddev-sans-first:87923.49 12:13:54 INFO - 2016-01-04 12:13:54,025 DEBUG : BROWSER_OUTPUT: Values: 576.3 612.6 624.3 628.6 631.3 34664.1 43192.1 43806.8 43849.6 43860.3 45876.8 46788.5 46815.8 46825.8 46868.5 8008.0 8134.6 9097.5 9298.1 10706.4 10810.1 10920.6 10952.9 10960.7 10965.4 10885.7 10940.4 10952.7 10954.8 10962.6 10745.0 10885.5 10962.0 10973.2 10987.4 10731.9 10873.3 10883.5 10888.1 10889.1 43405.6 43735.6 43761.3 43837.6 43852.0 3617.2 3707.0 4038.8 4302.7 4376.2 32645.0 32745.0 32769.3 32824.3 32847.7 3773.9 3821.4 3869.1 3966.5 3978.3 306390.0 307516.5 308299.0 309833.0 318466.0 64813.0 66165.3 66908.0 73170.0 75511.6 67181.0 67583.3 67701.3 67819.3 67821.3 17890.7 18003.8 18025.6 18036.9 18052.5 13697.3 13784.9 13794.7 13802.4 13977.3 21124.7 21327.3 21353.1 21372.0 21384.8 8713.5 8737.5 8770.6 8803.2 8998.0 1038.1 1144.9 1156.8 1182.1 1236.6 30550.8 30746.9 30849.0 31621.5 31756.7 1082.4 1111.3 1185.7 1242.9 1264.9 13597.8 16233.7 16311.7 16624.3 16630.9 364537.0 368834.0 369909.0 370160.0 370785.0 50698.7 51640.2 57362.4 57716.7 58840.4 789.2 1292.2 1473.6 1495.6 1496.6 30192.0 30904.4 30930.8 31601.0 31710.5 50824.0 51251.2 51279.7 51702.7 51860.0 322545.0 325498.0 325866.0 326051.0 326857.0 35632.3 36086.5 36246.8 36343.5 36359.8 47398.5 48543.0 48794.3 48921.0 49527.8 38959.4 39761.6 39973.4 40708.6 41040.4 38480.2 38965.0 39304.2 40441.4 40698.0 40392.6 40408.0 40539.0 40642.8 41128.6 32692.0 32997.3 35052.8 35787.3 36167.6 25545.5 27135.5 27437.9 27477.9 27533.6 12:13:54 INFO - 2016-01-04 12:13:54,025 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,025 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30951.91 Median:26387.47 stddev:103438.75 (392.0%) stddev-sans-first:103703.45 12:13:54 INFO - 2016-01-04 12:13:54,025 DEBUG : BROWSER_OUTPUT: Values: 382.2 382.6 385.2 386.3 388.7 12552.8 15061.7 17998.9 19494.9 19498.2 18542.8 18679.8 18701.3 19137.7 19481.8 5648.3 5761.8 5765.2 5783.9 5787.3 4404.5 4513.1 5737.3 6259.7 6434.7 6337.8 6427.0 6455.9 6458.7 6483.6 6448.7 6459.1 6487.2 6493.5 6495.5 4420.9 4496.4 5782.3 5785.7 5798.2 14897.6 14898.2 14948.9 15145.1 15342.0 2183.4 2194.6 2262.3 2301.2 2324.8 10929.9 11259.1 11262.5 11295.5 11372.3 1870.2 1884.1 1885.8 1886.4 1900.5 68678.3 69714.7 70447.6 70821.6 71091.3 7863.8 7881.9 7924.7 7956.6 7984.8 9492.0 9531.5 9544.8 9568.3 9574.7 6375.2 6424.1 6484.1 6508.0 6517.1 6730.9 6741.4 6745.3 6780.8 6817.2 3648.6 3683.8 3687.3 3694.0 3709.5 1261.5 1271.1 1276.8 1287.8 1322.0 487.7 525.5 533.3 540.2 543.2 11512.8 13141.2 13520.1 13690.2 13752.6 549.4 552.9 555.8 555.8 563.6 6999.1 7110.4 7258.8 7284.2 7294.2 614282.0 617681.0 636978.0 641175.0 647495.0 73831.6 74719.6 86680.6 87812.1 87995.0 568.4 582.2 583.3 583.4 583.6 11821.8 11916.2 12030.2 12058.1 12080.7 35002.3 35080.6 35369.6 36258.1 38238.2 52249.5 52939.2 53852.5 54319.5 54466.0 7360.9 7371.5 7553.7 7631.7 7648.3 11477.4 11502.0 13081.0 13419.4 13437.9 10368.4 11580.8 11804.5 11905.9 11938.5 11471.1 11528.1 11565.2 11721.3 11749.5 11506.8 11641.4 11994.9 12365.8 12516.6 7641.4 7687.5 7743.4 7767.7 7783.3 12133.6 12197.0 12227.3 12309.1 12418.4 12:13:54 INFO - 2016-01-04 12:13:54,025 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,025 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5714.29 Median:6893.48 stddev:2929.57 (42.5%) stddev-sans-first:2920.85 12:13:54 INFO - 2016-01-04 12:13:54,027 DEBUG : BROWSER_OUTPUT: Values: 1522.6 1527.2 1762.4 1818.7 1850.9 7345.8 7534.4 7965.5 8410.0 8420.9 7891.4 8106.3 8107.6 8108.1 8117.1 2301.9 2353.8 2367.2 2367.4 2371.5 2053.6 2115.1 2338.5 2342.9 2347.0 2373.0 2409.9 2413.3 2413.3 2413.7 2319.5 2343.3 2358.8 2359.0 2361.7 2350.5 2358.5 2375.0 2377.2 2377.7 8142.1 8207.3 8209.1 8220.9 8228.3 4810.9 4870.5 4878.1 4878.6 4880.7 3237.0 3253.6 3289.5 3292.5 3298.6 1004.2 1023.1 1024.3 1099.5 1111.5 11140.6 11153.0 11153.6 11159.7 11188.9 4382.5 4386.3 4415.5 4427.0 4434.9 9453.2 9464.3 9484.7 9501.8 9515.6 4947.8 4956.9 4961.0 4962.1 4962.4 4532.9 4535.5 4542.7 4545.7 4547.0 3822.4 3897.5 3899.7 3917.1 3931.9 1116.5 1224.2 1231.8 1237.4 1272.1 1134.0 1136.6 1141.4 1166.5 1201.7 7498.0 7584.7 7596.4 7629.7 7639.8 1113.8 1148.2 1186.9 1207.3 1226.2 6323.4 6328.7 6329.5 6381.3 6382.4 9243.0 9432.1 9441.6 9446.2 9455.2 9324.0 9336.3 9342.8 9374.7 9374.9 7532.2 7664.9 7666.8 7680.5 7690.3 7521.6 7536.0 7546.7 7571.1 7579.0 8617.7 8760.8 8838.3 8864.3 8895.6 8659.7 8783.2 9045.9 9049.6 9068.3 7362.6 7422.3 7432.3 7433.1 7440.3 7038.8 7580.3 7580.7 7856.3 7874.9 6862.7 7158.4 7442.7 7533.2 7560.1 7239.2 7307.9 7322.0 7349.5 7361.6 7594.5 7657.7 7662.0 7668.0 7669.3 6924.3 6993.2 7043.0 7043.3 7048.3 6984.5 7023.1 7025.7 7029.0 7029.9 12:13:54 INFO - 2016-01-04 12:13:54,027 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,027 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5330.62 Median:6125.12 stddev:1579.38 (25.8%) stddev-sans-first:1538.69 12:13:54 INFO - 2016-01-04 12:13:54,027 DEBUG : BROWSER_OUTPUT: Values: 336.6 345.5 355.6 361.0 363.4 5858.5 5893.9 5935.8 5939.2 5941.6 5760.5 5802.9 5861.7 5887.3 5894.1 4605.4 4619.1 4629.1 4634.4 4641.7 4618.1 4621.7 4621.7 4622.1 4627.5 4489.4 4581.3 4614.1 4633.4 4691.7 4644.8 4655.1 4673.8 4686.0 4693.9 3976.1 4144.5 4155.2 4156.9 4166.1 5510.7 5522.2 5623.8 5625.2 5651.8 5303.8 5312.0 5317.2 5318.4 5322.7 6246.7 6247.4 6248.3 6249.7 6255.4 4382.0 4433.7 4440.3 4458.4 4515.7 6400.7 6453.9 6459.7 6461.1 6477.2 4510.0 4512.0 4514.5 4515.8 4517.7 4579.8 4587.0 4587.5 4593.3 4600.0 5165.2 5255.3 5265.6 5279.3 5280.8 6379.8 6382.4 6386.1 6389.0 6391.6 1652.6 1669.2 1670.6 1671.3 1673.0 1121.6 1125.1 1130.8 1141.7 1151.1 6183.4 6188.8 6193.7 6197.1 6205.8 6577.4 6580.8 6584.1 6585.2 6592.7 6074.7 6185.5 6187.6 6190.4 6198.4 3612.2 3617.9 3642.4 3643.2 3645.7 6504.6 6595.0 6632.9 6745.3 6750.4 6620.6 6628.4 6652.3 6674.0 6678.5 6697.7 6700.7 6701.2 6709.4 6710.8 6351.7 6512.5 6519.8 6521.7 6530.8 6522.0 6522.4 6524.3 6527.9 6528.7 6813.0 6815.1 6816.7 6819.5 6820.1 4481.3 4596.0 4605.9 4613.1 4616.7 6630.8 6633.7 6636.4 6637.0 6642.7 6437.1 6453.0 6457.3 6462.8 6471.7 6352.2 6423.9 6445.8 6445.9 6459.9 6311.7 6318.7 6318.7 6319.5 6322.0 6487.0 6489.7 6490.1 6492.0 6499.3 6175.5 6263.7 6266.1 6266.9 6274.6 12:13:54 INFO - 2016-01-04 12:13:54,027 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,027 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14837.14 Median:2658.64 stddev:18119.72 (681.5%) stddev-sans-first:18139.98 12:13:54 INFO - 2016-01-04 12:13:54,028 DEBUG : BROWSER_OUTPUT: Values: 820.7 905.0 910.4 911.2 919.7 14121.9 14393.4 14398.9 14407.4 14568.3 12703.7 12878.5 12898.0 12914.4 12918.0 746.9 750.7 886.7 893.8 898.2 887.7 891.5 892.7 900.6 900.6 935.4 940.8 941.7 942.2 942.3 777.8 783.2 933.2 937.0 940.9 702.1 703.0 703.5 704.0 704.7 13575.0 13691.7 13736.5 13763.9 13774.5 13588.3 14340.4 14445.2 14463.0 14476.9 11180.8 11629.5 11633.8 11836.3 11999.0 3523.9 3533.2 3555.4 3574.1 3632.0 45278.0 46566.3 46570.8 46963.8 47073.3 8389.5 8398.0 8399.2 8419.7 8553.2 13226.6 13255.4 13265.9 13275.3 13279.6 7007.3 7015.9 7102.7 7234.7 7254.7 4690.8 4739.4 4760.7 4763.9 4836.6 2947.6 2960.7 2968.6 2969.8 2969.9 480.6 484.3 493.0 495.4 500.7 606.5 642.4 699.3 701.2 713.8 9024.8 9302.5 9815.7 9929.3 9969.3 723.6 749.2 752.7 778.5 780.6 4279.2 4441.5 4443.7 4447.5 4503.5 67802.7 68174.3 68549.7 68645.3 68760.3 57143.0 57576.7 57766.7 57979.1 57997.7 53119.0 53798.7 54137.2 54312.7 54776.7 8750.3 8857.6 9011.8 9113.6 9250.4 38490.6 39033.6 39272.0 39438.0 39882.2 47492.3 52230.0 52745.5 53462.7 53544.0 7886.2 8330.7 8376.6 8393.8 8476.7 12610.5 12768.7 12935.9 12950.5 13096.6 12004.6 12191.8 12261.7 12321.6 12405.7 11688.8 11959.8 12389.0 12426.9 13122.3 10104.6 12340.8 12375.5 12516.3 12626.8 10724.5 11024.7 11044.3 11127.9 11244.2 10326.3 10334.4 10580.2 10790.0 11050.8 12:13:54 INFO - 2016-01-04 12:13:54,029 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:13:54 INFO - 2016-01-04 12:13:54,029 DEBUG : BROWSER_OUTPUT: 12:13:54 INFO - 2016-01-04 12:13:54,593 INFO : Browser exited with error code: 0 12:13:54 INFO - 2016-01-04 12:13:54,614 INFO : Completed test dromaeo_css (00:18:29) 12:13:54 INFO - 2016-01-04 12:13:54,614 INFO : Starting test kraken 12:13:54 INFO - 2016-01-04 12:13:54,614 DEBUG : operating with platform_type : linux_ 12:13:54 INFO - 2016-01-04 12:13:54,615 INFO : Initialising browser for kraken test... 12:13:54 INFO - 2016-01-04 12:13:54,621 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3XtPjM/profile http://localhost:38748/getInfo.html 12:13:58 INFO - 2016-01-04 12:13:58,056 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:13:58 INFO - 2016-01-04 12:13:58,056 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:13:58 INFO - 2016-01-04 12:13:58,056 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:13:58 INFO - 2016-01-04 12:13:58,056 DEBUG : BROWSER_OUTPUT: __metrics 12:13:58 INFO - 2016-01-04 12:13:58,153 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] 12:13:59 INFO - 2016-01-04 12:13:59,362 INFO : Browser initialized. 12:13:59 INFO - 2016-01-04 12:13:59,362 INFO : Running cycle 1/1 for kraken test... 12:13:59 INFO - 2016-01-04 12:13:59,362 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3XtPjM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:14:10 INFO - 2016-01-04 12:14:10,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 128892928 12:14:10 INFO - 2016-01-04 12:14:10,853 DEBUG : BROWSER_OUTPUT: 12:14:13 INFO - 2016-01-04 12:14:13,273 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38748/tests/kraken/driver.html?testName=audio-beat-detection) 12:14:13 INFO - 2016-01-04 12:14:13,840 DEBUG : BROWSER_OUTPUT: RSS: Main: 165965824 12:14:13 INFO - 2016-01-04 12:14:13,841 DEBUG : BROWSER_OUTPUT: 12:14:16 INFO - 2016-01-04 12:14:16,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38748/tests/kraken/driver.html?testName=audio-dft) 12:14:16 INFO - 2016-01-04 12:14:16,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 160968704 12:14:16 INFO - 2016-01-04 12:14:16,984 DEBUG : BROWSER_OUTPUT: 12:14:19 INFO - 2016-01-04 12:14:19,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38748/tests/kraken/driver.html?testName=audio-fft) 12:14:20 INFO - 2016-01-04 12:14:20,294 DEBUG : BROWSER_OUTPUT: RSS: Main: 158691328 12:14:20 INFO - 2016-01-04 12:14:20,294 DEBUG : BROWSER_OUTPUT: 12:14:22 INFO - 2016-01-04 12:14:22,245 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38748/tests/kraken/driver.html?testName=audio-oscillator) 12:14:22 INFO - 2016-01-04 12:14:22,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 160047104 12:14:22 INFO - 2016-01-04 12:14:22,812 DEBUG : BROWSER_OUTPUT: 12:14:24 INFO - 2016-01-04 12:14:24,949 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38748/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:14:25 INFO - 2016-01-04 12:14:25,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 156467200 12:14:25 INFO - 2016-01-04 12:14:25,511 DEBUG : BROWSER_OUTPUT: 12:14:28 INFO - 2016-01-04 12:14:28,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38748/tests/kraken/driver.html?testName=imaging-darkroom) 12:14:28 INFO - 2016-01-04 12:14:28,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 155934720 12:14:28 INFO - 2016-01-04 12:14:28,974 DEBUG : BROWSER_OUTPUT: 12:14:32 INFO - 2016-01-04 12:14:32,336 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38748/tests/kraken/driver.html?testName=imaging-desaturate) 12:14:32 INFO - 2016-01-04 12:14:32,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 158003200 12:14:32 INFO - 2016-01-04 12:14:32,899 DEBUG : BROWSER_OUTPUT: 12:14:35 INFO - 2016-01-04 12:14:35,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38748/tests/kraken/driver.html?testName=json-parse-financial) 12:14:36 INFO - 2016-01-04 12:14:36,210 DEBUG : BROWSER_OUTPUT: RSS: Main: 157499392 12:14:36 INFO - 2016-01-04 12:14:36,210 DEBUG : BROWSER_OUTPUT: 12:14:37 INFO - 2016-01-04 12:14:37,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38748/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:14:38 INFO - 2016-01-04 12:14:38,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 161849344 12:14:38 INFO - 2016-01-04 12:14:38,291 DEBUG : BROWSER_OUTPUT: 12:14:40 INFO - 2016-01-04 12:14:40,042 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:14:40 INFO - 2016-01-04 12:14:40,628 DEBUG : BROWSER_OUTPUT: RSS: Main: 160419840 12:14:40 INFO - 2016-01-04 12:14:40,628 DEBUG : BROWSER_OUTPUT: 12:14:42 INFO - 2016-01-04 12:14:42,560 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:14:43 INFO - 2016-01-04 12:14:43,126 DEBUG : BROWSER_OUTPUT: RSS: Main: 167931904 12:14:43 INFO - 2016-01-04 12:14:43,126 DEBUG : BROWSER_OUTPUT: 12:14:45 INFO - 2016-01-04 12:14:45,426 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:14:46 INFO - 2016-01-04 12:14:46,000 DEBUG : BROWSER_OUTPUT: RSS: Main: 172920832 12:14:46 INFO - 2016-01-04 12:14:46,000 DEBUG : BROWSER_OUTPUT: 12:14:48 INFO - 2016-01-04 12:14:48,399 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:14:48 INFO - 2016-01-04 12:14:48,965 DEBUG : BROWSER_OUTPUT: RSS: Main: 169086976 12:14:48 INFO - 2016-01-04 12:14:48,965 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,594 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38748/tests/kraken/driver.html?testName=ai-astar) 12:14:50 INFO - 2016-01-04 12:14:50,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 228487168 12:14:50 INFO - 2016-01-04 12:14:50,852 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,852 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:14:50 INFO - 2016-01-04 12:14:50,852 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;103;108;110;110;100;109;107;109 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;121;154;156;125;157;156;128;156;122 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;159;160;160;160;159;162;163;160;159 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;138;72;72;71;72;149;72 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;88;88;138;89;88;88;131;89;89;135 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;110;110;109;109;110;110 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;148;145;146;145;147;145;146 12:14:50 INFO - 2016-01-04 12:14:50,853 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;95;95;96;95;95 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;61;55;54;57;55;57;57;54;54 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;65;64;64;64;64;64;64;64 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;78;79;77;78;80;79;78;77;78 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;115;116;104;108;113;116;113;107;119 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;138;136;136;135;134;135;134;136;136 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;65;67;64;73;63;62;63;61;62 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,851 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: __startTimestamp1451938490846__endTimestamp 12:14:50 INFO - 2016-01-04 12:14:50,854 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:109.00 stddev:3.27 (3.0%) stddev-sans-first:3.46 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 103.0 108.0 110.0 110.0 100.0 109.0 107.0 109.0 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.20 Median:155.00 stddev:16.01 (10.3%) stddev-sans-first:16.89 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: Values: 147.0 121.0 154.0 156.0 125.0 157.0 156.0 128.0 156.0 122.0 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.34 (0.8%) stddev-sans-first:1.39 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: Values: 161.0 159.0 160.0 160.0 160.0 159.0 162.0 163.0 160.0 159.0 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,855 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.10 Median:72.00 stddev:30.37 (42.2%) stddev-sans-first:31.71 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 138.0 72.0 72.0 71.0 72.0 149.0 72.0 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.30 Median:88.00 stddev:22.40 (25.5%) stddev-sans-first:23.15 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: Values: 88.0 88.0 138.0 89.0 88.0 88.0 131.0 89.0 89.0 135.0 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 110.0 110.0 109.0 109.0 110.0 110.0 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.05 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 148.0 145.0 146.0 145.0 147.0 145.0 146.0 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,856 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:2.36 (4.2%) stddev-sans-first:2.29 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: Values: 53.0 61.0 55.0 54.0 57.0 55.0 57.0 57.0 54.0 54.0 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 12:14:50 INFO - 2016-01-04 12:14:50,857 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.00 stddev:0.99 (1.3%) stddev-sans-first:0.97 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: Values: 77.0 78.0 79.0 77.0 78.0 80.0 79.0 78.0 77.0 78.0 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.70 Median:115.50 stddev:4.81 (4.2%) stddev-sans-first:4.95 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: Values: 116.0 115.0 116.0 104.0 108.0 113.0 116.0 113.0 107.0 119.0 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.80 Median:136.00 stddev:1.40 (1.0%) stddev-sans-first:1.24 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: Values: 138.0 138.0 136.0 136.0 135.0 134.0 135.0 134.0 136.0 136.0 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.50 stddev:3.47 (5.4%) stddev-sans-first:3.68 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 67.0 64.0 73.0 63.0 62.0 63.0 61.0 62.0 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:14:50 INFO - 2016-01-04 12:14:50,858 DEBUG : BROWSER_OUTPUT: 12:14:51 INFO - 2016-01-04 12:14:51,514 INFO : Browser exited with error code: 0 12:14:51 INFO - 2016-01-04 12:14:51,567 INFO : Completed test kraken (00:00:56) 12:14:51 INFO - 2016-01-04 12:14:51,567 INFO : Starting test v8_7 12:14:51 INFO - 2016-01-04 12:14:51,567 DEBUG : operating with platform_type : linux_ 12:14:51 INFO - 2016-01-04 12:14:51,568 INFO : Initialising browser for v8_7 test... 12:14:51 INFO - 2016-01-04 12:14:51,574 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsPmDgO/profile http://localhost:38748/getInfo.html 12:14:55 INFO - 2016-01-04 12:14:55,026 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:14:55 INFO - 2016-01-04 12:14:55,027 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:14:55 INFO - 2016-01-04 12:14:55,027 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:14:55 INFO - 2016-01-04 12:14:55,027 DEBUG : BROWSER_OUTPUT: __metrics 12:14:55 INFO - 2016-01-04 12:14:55,083 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] 12:14:56 INFO - 2016-01-04 12:14:56,261 INFO : Browser initialized. 12:14:56 INFO - 2016-01-04 12:14:56,262 INFO : Running cycle 1/1 for v8_7 test... 12:14:56 INFO - 2016-01-04 12:14:56,263 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsPmDgO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:15:07 INFO - 2016-01-04 12:15:07,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 128446464 12:15:07 INFO - 2016-01-04 12:15:07,858 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,208 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38748/tests/v8_7/run.html (next: http://localhost:38748/tests/v8_7/run.html) 12:15:29 INFO - 2016-01-04 12:15:29,459 DEBUG : BROWSER_OUTPUT: RSS: Main: 416194560 12:15:29 INFO - 2016-01-04 12:15:29,459 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |0;Richards;166.66666666666666 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.56114617090228 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.6420581655481 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4995.024875621891 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |4;RayTrace;941.6195856873823 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |5;Earley;555.5555555555555 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |6;Boyer;10416.666666666666 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |7;RegExp;23880.95238095238 12:15:29 INFO - 2016-01-04 12:15:29,460 DEBUG : BROWSER_OUTPUT: |8;Splay;539.5373856912319 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: __startTimestamp1451938529456__endTimestamp 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: Number of tests: 10 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,461 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.67 Median:166.67 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,462 DEBUG : BROWSER_OUTPUT: Values: 166.7 12:15:29 INFO - 2016-01-04 12:15:29,462 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,462 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.56 Median:170.56 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,462 DEBUG : BROWSER_OUTPUT: Values: 170.6 12:15:29 INFO - 2016-01-04 12:15:29,463 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,463 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.64 Median:279.64 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,463 DEBUG : BROWSER_OUTPUT: Values: 279.6 12:15:29 INFO - 2016-01-04 12:15:29,463 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,463 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4995.02 Median:4995.02 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,464 DEBUG : BROWSER_OUTPUT: Values: 4995.0 12:15:29 INFO - 2016-01-04 12:15:29,464 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,464 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:941.62 Median:941.62 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,464 DEBUG : BROWSER_OUTPUT: Values: 941.6 12:15:29 INFO - 2016-01-04 12:15:29,464 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,464 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:555.56 Median:555.56 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,464 DEBUG : BROWSER_OUTPUT: Values: 555.6 12:15:29 INFO - 2016-01-04 12:15:29,465 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,465 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10416.67 Median:10416.67 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,465 DEBUG : BROWSER_OUTPUT: Values: 10416.7 12:15:29 INFO - 2016-01-04 12:15:29,465 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,465 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23880.95 Median:23880.95 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,465 DEBUG : BROWSER_OUTPUT: Values: 23881.0 12:15:29 INFO - 2016-01-04 12:15:29,465 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,466 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:539.54 Median:539.54 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,466 DEBUG : BROWSER_OUTPUT: Values: 539.5 12:15:29 INFO - 2016-01-04 12:15:29,466 DEBUG : BROWSER_OUTPUT: 12:15:29 INFO - 2016-01-04 12:15:29,466 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 12:15:29 INFO - 2016-01-04 12:15:29,466 DEBUG : BROWSER_OUTPUT: Values: 4817.3 12:15:29 INFO - 2016-01-04 12:15:29,466 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:15:29 INFO - 2016-01-04 12:15:29,466 DEBUG : BROWSER_OUTPUT: 12:15:30 INFO - 2016-01-04 12:15:30,149 INFO : Browser exited with error code: 0 12:15:30 INFO - 2016-01-04 12:15:30,166 INFO : Completed test v8_7 (00:00:38) 12:15:30 INFO - 2016-01-04 12:15:30,537 INFO : Completed test suite (00:20:05) 12:15:30 INFO - 2016-01-04 12:15:30,538 DEBUG : Working with test: dromaeo_css 12:15:30 INFO - 2016-01-04 12:15:30,538 DEBUG : Generating results file: dromaeo_css 12:15:30 INFO - 2016-01-04 12:15:30,539 DEBUG : Working with test: kraken 12:15:30 INFO - 2016-01-04 12:15:30,540 DEBUG : Generating results file: kraken 12:15:30 INFO - 2016-01-04 12:15:30,540 INFO : javascript benchmark 12:15:30 INFO - 2016-01-04 12:15:30,540 DEBUG : Working with test: v8_7 12:15:30 INFO - 2016-01-04 12:15:30,540 DEBUG : Generating results file: v8_7 12:15:30 INFO - 2016-01-04 12:15:30,541 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:15:30 INFO - 2016-01-04 12:15:30,602 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 12:15:30 INFO - 2016-01-04 12:15:30,603 DEBUG : process_Request line: dromaeo_css 7162.46 graph.html#tests=[[72,53,35]] 12:15:30 INFO - 2016-01-04 12:15:30,603 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:15:30 INFO - 2016-01-04 12:15:30,706 DEBUG : process_Request line: kraken 1460.20 graph.html#tests=[[232,53,35]] 12:15:30 INFO - 2016-01-04 12:15:30,706 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:15:30 INFO - 2016-01-04 12:15:30,812 DEBUG : process_Request line: v8_7 23256.53 graph.html#tests=[[230,53,35]] 12:15:30 INFO - 2016-01-04 12:15:30,814 INFO : javascript benchmark 12:15:30 INFO - 2016-01-04 12:15:30,817 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3266.2814180880205, 3332.483465062913, 3360.075979105747, 3368.6968172864435, 3373.3927892979254, 5576.052071147905, 5603.887922241553, 5608.298962629671, 5634.774609015634, 5638.267025286712, 5572.0527510323245, 5609.775608975647, 5621.184928185411, 5626.043572592659, 5635.239242702234, 4959.727215003173, 5090.821821236248, 5119.0, 5197.532222100011, 5236.554892834114, 5146.691198528081, 5163.535281824635, 5165.535101840834, 5167.147420675584, 5174.715390653512, 5047.67190132642, 5078.111330517163, 5098.566621837148, 5154.278401023859, 5186.636935414523, 5078.289039534449, 5079.644424890259, 5082.390113186441, 5084.593232541388, 5100.132977393852, 5049.949500504985, 5056.544910958031, 5102.974485127587, 5109.3613298337705, 5166.328377310963, 5283.656562323436, 5373.167159090347, 5385.946140538584, 5493.725313734337, 5512.5589952803675, 6013.669248191348, 6056.454919057306, 6060.696965151725, 6067.969660151671, 6083.48290395317, 5921.319048309413, 6113.113598528189, 6117.84705382369, 6123.755049798047, 6134.2332208473945, 5583.162525621149, 5621.297337832771, 5699.800506982236, 5701.543876489913, 5722.856928576777, 6018.939810601925, 6020.337762846083, 6023.186869772612, 6044.365341639064, 6175.0, 5721.685307308055, 5833.620814647119, 5848.502877255481, 5855.94144058554, 5881.617694849821, 5998.0, 6064.666443345658, 6107.725152368154, 6126.081087836771, 6141.692915354214, 6037.939620603738, 6060.2424696912885, 6062.181605483203, 6067.969660151671, 6077.878442431215, 5772.538196944234, 5861.325947516005, 5888.0, 6052.606580572248, 6071.483923866521, 4500.099980003947, 4501.369808226851, 4505.053938672915, 4505.797239124247, 4507.0, 4031.8387264509092, 4115.36211887158, 4128.60778226068, 4143.0678097427835, 4154.293770059036, 5741.5119714824705, 5750.281214848144, 5775.768969241183, 5790.971045144832, 5821.27234095738, 5814.534837213013, 5925.9407405925385, 6119.418655227746, 6124.601900876429, 6142.539309551801, 5683.8863222736145, 5750.683712395775, 5761.423857614205, 5762.481376676119, 5793.333766616893, 5260.658057226268, 5266.7629956652045, 5277.656952298088, 5289.656172348785, 5291.3385826771655, 5959.970200149059, 6059.394060593993, 6120.479759220516, 6205.348438413992, 6221.222347206599, 6082.513398928164, 6088.238970128734, 6100.115483254992, 6100.725467353891, 6129.141920131096, 6236.158118654105, 6248.312685604671, 6269.404406581368, 6271.184745982993, 6283.748650053946, 5789.623674461147, 5885.47030767233, 5892.35184129754, 5983.311919129356, 6006.729697163551, 6081.996470582304, 6082.026875699867, 6086.69566521681, 6093.969530152321, 6099.085137229451, 5920.200772895776, 5970.432808883149, 5970.880582388415, 5976.760929562943, 5982.252218472691, 5822.854428639318, 5844.064949608042, 5922.496587789914, 5928.081147422156, 5943.702814859326, 5785.479306862402, 5833.241678581757, 5838.912416313844, 5839.153322768259, 5877.147813566923, 5766.509846663081, 5951.821445356646, 5957.582969192115, 5978.9402105980125, 5992.730327135202, 5730.598858080061, 5765.769369225184, 5840.036393995113, 5843.152742852178, 5847.707614619167], "unit": "ms", "name": "dojo.html", "value": 5554.009518024659}, {"lowerIsBetter": false, "replicates": [576.3084298841392, 612.6079309242083, 624.2508989212944, 628.6196850905202, 631.2929518938787, 34664.13339666509, 43192.13615727687, 43806.78096609517, 43849.56150438495, 43860.34209486852, 45876.770616146954, 46788.53211467884, 46815.76592117034, 46825.76587117068, 46868.53131468693, 8008.0, 8134.633941472648, 9097.454152750839, 9298.116678915492, 10706.357618542874, 10810.135189184886, 10920.617778377758, 10952.945235273852, 10960.671179864617, 10965.396903170322, 10885.673429797118, 10940.3982780947, 10952.671419857417, 10954.780904381907, 10962.616308429206, 10745.0, 10885.510152043176, 10962.0, 10973.177011724152, 10987.39569323687, 10731.892681073168, 10873.293235939718, 10883.510242039049, 10888.074513666348, 10889.128869690407, 43405.565944340786, 43735.562644373786, 43761.343579846325, 43837.56162438367, 43852.0, 3617.186133120027, 3707.0361705956648, 4038.8182531785847, 4302.741835489881, 4376.190404775127, 32645.020649380556, 32745.0, 32769.344613107845, 32824.34351312961, 32847.67152328494, 3773.9056523586855, 3821.426785982125, 3869.090763670535, 3966.5438474575503, 3978.264021156095, 306390.0, 307516.46241768874, 308299.0, 309833.0, 318466.0, 64813.0, 66165.33834661591, 66908.0, 73170.0, 75511.62244188854, 67181.0, 67583.32416675868, 67701.3229867705, 67819.32180678229, 67821.3217867815, 17890.731639025165, 18003.819961800476, 18025.63948721018, 18036.90981545084, 18052.54868628295, 13697.315134243248, 13784.862151378558, 13794.65513362174, 13802.447902084004, 13977.301134943416, 21124.683129753066, 21327.253546125812, 21353.14587416486, 21372.0, 21384.78615213859, 8713.477191368538, 8737.519436431057, 8770.605322760492, 8803.163699448542, 8997.955010224907, 1038.0864838941684, 1144.9084073274119, 1156.8033434316187, 1182.148852825964, 1236.567201479493, 30550.847245764075, 30746.923857665322, 30849.0, 31621.525677114863, 31756.682433175836, 1082.377632861108, 1111.344306858957, 1185.6561597136733, 1242.9005679545796, 1264.9114561980573, 13597.796033059512, 16233.675326493403, 16311.673766524604, 16624.3350265988, 16630.916845415697, 364537.0, 368834.0, 369909.0, 370160.0443984502, 370785.0, 50698.746506267235, 51640.225396619084, 57362.42637573571, 57716.711416442646, 58840.411595883495, 789.2242454477425, 1292.237579827906, 1473.5579326202096, 1495.5513345996158, 1496.5809573319489, 30192.0, 30904.381912362078, 30930.845345773127, 31601.0, 31710.524342134424, 50824.0, 51251.2312315323, 51279.743601281756, 51702.741486292325, 51860.0, 322545.0, 325498.0, 325866.0, 326051.0, 326857.0, 35632.28735425329, 36086.45870311895, 36246.818765906, 36343.454848177826, 36359.81820090883, 47398.526014739415, 48543.0, 48794.26808597803, 48921.0, 49527.75236123797, 38959.41560876646, 39761.60238397695, 39973.40039899462, 40708.59291407166, 41040.384394233515, 38480.23039539249, 38965.0, 39304.21391572095, 40441.39337909993, 40698.0, 40392.596074038884, 40408.0, 40539.0, 40642.79678601588, 41128.58871411248, 32692.0, 32997.34005319928, 35052.82473587616, 35787.28425431425, 36167.638323616426, 25545.48909021772, 27135.45729085368, 27437.86281068582, 27477.862610686818, 27533.586996195474], "unit": "ms", "name": "ext.html", "value": 20679.823442619225}, {"lowerIsBetter": false, "replicates": [382.22408510723244, 382.61929380266605, 385.20839674468954, 386.27958856732187, 388.6638058079761, 12552.81170782439, 15061.698766024672, 17998.920064796104, 19494.90252548737, 19498.220071197153, 18542.814571854276, 18679.81320186801, 18701.34545290915, 19137.71293430596, 19481.805181948177, 5648.322201335835, 5761.769529218826, 5765.2216950711745, 5783.884322313551, 5787.334456537489, 4404.4934832494355, 4513.14250292445, 5737.340205876315, 6259.749610015594, 6434.742610295582, 6337.8415539611415, 6426.967865160691, 6455.870882582346, 6458.709358078874, 6483.578567393128, 6448.742050317982, 6459.128017717619, 6487.18910136233, 6493.545451818374, 6495.480361571086, 4420.889477763081, 4496.392986946753, 5782.306123265193, 5785.739641716133, 5798.217240672498, 14897.627559310995, 14898.180600066991, 14948.925255373653, 15145.091294522254, 15342.0, 2183.3995651195887, 2194.5501172259646, 2262.332611879483, 2301.2175860207444, 2324.8140148788057, 10929.91186521976, 11259.099272058216, 11262.54949802007, 11295.49170287339, 11372.260803047775, 1870.2425517665367, 1884.076802366844, 1885.764279465067, 1886.368066697658, 1900.4583693647244, 68678.31321686819, 69714.65142674305, 70447.64776176137, 70821.64589177021, 71091.28908710899, 7863.764087077397, 7881.921180788233, 7924.683012679428, 7956.602169891482, 7984.800379990546, 9492.0, 9531.523423828781, 9544.761380965392, 9568.330216884751, 9574.66488672907, 6375.171227740364, 6424.100625912376, 6484.1246431731115, 6508.0, 6517.120188774549, 6730.899036514456, 6741.426978706865, 6745.325467453314, 6780.796576102724, 6817.181938167451, 3648.635136486383, 3683.8158092095287, 3687.3362794697214, 3693.981530092386, 3709.4621279914263, 1261.482792055253, 1271.1483306184878, 1276.7765641012786, 1287.76820172368, 1321.9801702974464, 487.7000644603543, 525.4535283305396, 533.3280067115462, 540.2193829915734, 543.2204786131913, 11512.76974460506, 13141.211527308393, 13520.12119212248, 13690.178589284675, 13752.587422377344, 549.4340828946192, 552.9060059789846, 555.8054680861746, 555.8165805284247, 563.6223730100842, 6999.0551275577145, 7110.431165506747, 7258.818529536698, 7284.162321333115, 7294.197638259893, 614282.0, 617681.0, 636978.0, 641175.0, 647495.0, 73831.63084184544, 74719.62640186764, 86680.56659716662, 87812.12187878168, 87995.0, 568.4145330309788, 582.2488989203964, 583.270911360799, 583.3903570768557, 583.6089819820645, 11821.82267266009, 11916.165868389477, 12030.15788894769, 12058.095642826647, 12080.697982550506, 35002.29995400027, 35080.649193507736, 35369.64630353663, 36258.093547660465, 38238.235235295695, 52249.47750522598, 52939.205911912126, 53852.46147538631, 54319.45680543144, 54466.0, 7360.852782944204, 7371.520851144873, 7553.7356192533, 7631.69473221065, 7648.311651951351, 11477.36874471912, 11502.0, 13081.0, 13419.396127174105, 13437.86562134366, 10368.377897326185, 11580.768384632092, 11804.527818887149, 11905.940470297593, 11938.522459101887, 11471.08231341474, 11528.135389845962, 11565.190436669689, 11721.296722196696, 11749.47127379253, 11506.827397588875, 11641.41792910368, 11994.880051199376, 12365.814512782135, 12516.624501264976, 7641.350485208597, 7687.461877668509, 7743.419243556868, 7767.650455729393, 7783.299503044753, 12133.575324863587, 12197.0, 12227.266364018187, 12309.076819238413, 12418.37908104573], "unit": "ms", "name": "jquery.html", "value": 7876.574857429148}, {"lowerIsBetter": false, "replicates": [1522.6345677037507, 1527.1829571179414, 1762.383165891937, 1818.6999145141049, 1850.9259629614821, 7345.8163545911375, 7534.3972482201425, 7965.522068675877, 8409.957950210248, 8420.873686894694, 7891.447598668095, 8106.270435660789, 8107.594620268977, 8108.067572229195, 8117.066537348207, 2301.850379725321, 2353.7763912428377, 2367.159658321297, 2367.3844800351862, 2371.5019845832394, 2053.6303465376227, 2115.122224276923, 2338.5089131282366, 2342.8594284342907, 2346.9647955280684, 2372.9762702372927, 2409.8674572898485, 2413.3001429585397, 2413.348395933097, 2413.6741539892064, 2319.512902290521, 2343.308723926446, 2358.752331005237, 2358.9882050589636, 2361.7284012338628, 2350.5416443793465, 2358.469344397514, 2375.0143690368477, 2377.1561095811085, 2377.667126602277, 8142.104368519458, 8207.261346478786, 8209.13804050572, 8220.876686849702, 8228.30059444942, 4810.879728006793, 4870.537298956593, 4878.0975519529, 4878.585320247783, 4880.731559764212, 3237.0, 3253.6258330292085, 3289.506574013917, 3292.522584225298, 3298.6041674999155, 1004.1615251265202, 1023.0792286941737, 1024.303473637926, 1099.5162128663362, 1111.5331560744426, 11140.610078647209, 11153.0, 11153.609623663133, 11159.665210043711, 11188.888111118948, 4382.496012958487, 4386.2762644163995, 4415.536368681306, 4427.048184640318, 4434.866953991385, 9453.243740500744, 9464.337496375188, 9484.668036618685, 9501.762455938657, 9515.57179926905, 4947.826826061142, 4956.851294461172, 4960.975195124001, 4962.057209130254, 4962.429320628102, 4532.9093418132115, 4535.523770004168, 4542.727436353829, 4545.727256364628, 4547.045120524661, 3822.4075268333454, 3897.512810898638, 3899.6880249579967, 3917.0794863207125, 3931.901702457404, 1116.5310569560804, 1224.210384302138, 1231.8275441438207, 1237.3998610673816, 1272.1222356573935, 1134.0, 1136.5510623303915, 1141.383652827464, 1166.4867458318313, 1201.7416255504934, 7497.962510187414, 7584.734534291273, 7596.354309883612, 7629.656665450068, 7639.847203055908, 1113.821788513834, 1148.242160174281, 1186.8694443611196, 1207.3178654060428, 1226.178460431513, 6323.367663233732, 6328.71520781566, 6329.461995730415, 6381.298057213703, 6382.361763823674, 9243.0, 9432.056794320513, 9441.622335106656, 9446.197073248713, 9455.243580513543, 9323.953380233192, 9336.299777516713, 9342.766430839283, 9374.67188648394, 9374.85937710935, 7532.246775322534, 7664.885026724603, 7666.846663066596, 7680.5391676499585, 7690.346320562703, 7521.623918804147, 7536.020317358708, 7546.698132074655, 7571.053618297713, 7579.0, 8617.741467755977, 8760.824783504167, 8838.29293656493, 8864.290856731317, 8895.555222238992, 8659.740207793777, 8783.209511144029, 9045.864312035194, 9049.638014479611, 9068.274538037072, 7362.595057271901, 7422.257774222535, 7432.2567743225245, 7433.145188303414, 7440.330370266702, 7038.753643622654, 7580.279873412017, 7580.658870350737, 7856.292933636, 7874.921250787648, 6862.656867156722, 7158.391536719437, 7442.665080071301, 7533.20901305377, 7560.130584982798, 7239.203687594466, 7307.8538429232185, 7322.0, 7349.522281051714, 7361.595112268877, 7594.468387212842, 7657.6554055066545, 7661.961690191513, 7668.041494813148, 7669.309762121436, 6924.307569243036, 6993.230744617987, 7043.049188359506, 7043.2604576518725, 7048.295170482911, 6984.511084224056, 7023.086998690138, 7025.718971241093, 7029.0, 7029.929700703132], "unit": "ms", "name": "mootools.html", "value": 4698.567637550477}, {"lowerIsBetter": false, "replicates": [336.6280260312355, 345.5456075261031, 355.5520044743624, 361.0414349901014, 363.39494741255794, 5858.472737453629, 5893.8821223575505, 5935.76256949722, 5939.168516407701, 5941.6435013899145, 5760.45275698809, 5802.854928626786, 5861.706914654272, 5887.264091988502, 5894.056950887859, 4605.401297831278, 4619.053094115707, 4629.097326021427, 4634.37435946148, 4641.744704041276, 4618.076384723052, 4621.653375996797, 4621.74580398078, 4622.098690755304, 4627.467841198271, 4489.438820147481, 4581.335706322581, 4614.054118905633, 4633.4439867215815, 4691.71849689018, 4644.837430689943, 4655.092257009885, 4673.83641572545, 4685.976570117145, 4693.882652933687, 3976.1053762903402, 4144.5026596808575, 4155.23128221277, 4156.875293741193, 4166.062635906927, 5510.6969116698565, 5522.22688823565, 5623.803166889139, 5625.184348269478, 5651.8304450866535, 5303.8408847734245, 5312.0, 5317.229001794757, 5318.388385335697, 5322.7072511011875, 6246.718897649616, 6247.375262473716, 6248.31268560458, 6249.687515624246, 6255.374462553754, 4382.0, 4433.733975961452, 4440.267355886275, 4458.398116254297, 4515.729056256635, 6400.679966001681, 6453.935460645381, 6459.741610335628, 6461.0631458438265, 6477.222733272038, 4510.015593139008, 4512.007358381151, 4514.480834704051, 4515.796789144496, 4517.728936263835, 4579.816807327669, 4586.977065114653, 4587.495375508692, 4593.333966574828, 4600.0, 5165.225216217522, 5255.264263003182, 5265.578753699695, 5279.340082489689, 5280.841574752763, 6379.808605741742, 6382.4255816975765, 6386.074019267273, 6389.041643753381, 6391.648459334781, 1652.5868532866784, 1669.2404955745108, 1670.5573023148875, 1671.2980548169799, 1673.029642807169, 1121.5738019552518, 1125.0605744203517, 1130.8473356096822, 1141.658551202334, 1151.067635215478, 6183.350748171467, 6188.752449901954, 6193.659348735863, 6197.101420294122, 6205.844853878689, 6577.375149360803, 6580.76967306142, 6584.078229047937, 6585.2426970898005, 6592.736290548421, 6074.696265186723, 6185.474234690102, 6187.566870319034, 6190.350013248634, 6198.411150940653, 3612.205314830733, 3617.8914632561064, 3642.3990041642833, 3643.2167084076523, 3645.653662902073, 6504.609723416515, 6595.0, 6632.900506492407, 6745.325467453314, 6750.3924646782025, 6620.602763834186, 6628.370304821035, 6652.334766523406, 6673.966630166915, 6678.53250272486, 6697.732090716317, 6700.698468568926, 6701.195856497154, 6709.396154346035, 6710.83222919431, 6351.650659213788, 6512.544121911421, 6519.804405867641, 6521.67391630426, 6530.8367290818105, 6521.9564869620635, 6522.380373864476, 6524.314946930694, 6527.934720652733, 6528.67356632176, 6812.965935170292, 6815.079964204769, 6816.693248803915, 6819.522633415613, 6820.113385259884, 4481.282994720829, 4596.034832685174, 4605.93091103627, 4613.0773845230415, 4616.653750968623, 6630.83422914431, 6633.70148343316, 6636.435902948304, 6636.966815165893, 6642.701078451385, 6437.066625339393, 6452.9677351612945, 6457.289698133295, 6462.773802916875, 6471.676416179078, 6352.237731472253, 6423.871522569616, 6445.806625801233, 6445.935540644534, 6459.870802584016, 6311.715972781144, 6318.652474113968, 6318.6840657965995, 6319.52603554726, 6321.956877115408, 6487.0, 6489.675516224264, 6490.091387205889, 6491.967540162269, 6499.28507864144, 6175.536834737323, 6263.718132683949, 6266.091416744638, 6266.905996410149, 6274.623522588659], "unit": "ms", "name": "prototype.html", "value": 4826.47014216185}, {"lowerIsBetter": false, "replicates": [820.6799348254181, 904.9909500904989, 910.3809409601471, 911.1981456318434, 919.6919032124238, 14121.929390353047, 14393.424263029492, 14398.856011439895, 14407.351669174886, 14568.344424500896, 12703.745925081494, 12878.549250776225, 12898.0, 12914.35428228587, 12918.03114766394, 746.936510396617, 750.6772088002157, 886.6586364249746, 893.7944272817256, 898.1557336104074, 887.6715615222377, 891.545311890937, 892.7143314139446, 900.6172376739892, 900.6397441023611, 935.4013431403907, 940.8306504829128, 941.7033634405141, 942.1614762861059, 942.2650332740474, 777.8249893773884, 783.2441693765516, 933.2254228989956, 936.976575585609, 940.9482478463682, 702.1083224305155, 702.9753958611424, 703.5391818358983, 703.9929600703979, 704.7075463682565, 13575.049746517747, 13691.726165476635, 13736.450541978309, 13763.862361376358, 13774.517891873737, 13588.25264610446, 14340.42638294467, 14445.205513696743, 14463.0, 14476.927615361961, 11180.832287515694, 11629.534818607246, 11633.825492617532, 11836.349000804952, 11999.0, 3523.9119022024397, 3533.1873669056263, 3555.4311310190506, 3574.088607405121, 3632.037510059836, 45278.0, 46566.301505477444, 46570.76714616439, 46963.76518117422, 47073.29390059152, 8389.454685445426, 8397.95801020991, 8399.160083991552, 8419.74740757766, 8553.187447192508, 13226.603201903958, 13255.403506842214, 13265.933670331586, 13275.269860157785, 13279.601611951657, 7007.334303241184, 7015.8596828063155, 7102.715891364288, 7234.674439650228, 7254.673539690621, 4690.7654617269, 4739.431268247763, 4760.738159401266, 4763.928541071887, 4836.637252206098, 2947.64628244612, 2960.718731720483, 2968.569557414163, 2969.792114551961, 2969.866356013941, 480.6371189751715, 484.3461327208241, 492.9802807887716, 495.37087898368867, 500.74712270303263, 606.4693393280879, 642.4154019841922, 699.3006993006993, 701.193627328566, 713.8144082538473, 9024.819503610022, 9302.488363139957, 9815.656452024145, 9929.30494865367, 9969.252306077073, 723.64541374726, 749.2133260076897, 752.6763491698622, 778.4628606261662, 780.621398621668, 4279.2083464559155, 4441.451552562664, 4443.688941774045, 4447.510773814878, 4503.504614492404, 67802.66098669475, 68174.31825681779, 68549.65725171342, 68645.3135468639, 68760.3123968764, 57143.0, 57576.71211643915, 57766.71116644474, 57979.13031304534, 57997.71001144967, 53119.0, 53798.731006344715, 54137.18794218011, 54312.72843635757, 54776.72611636996, 8750.299976001777, 8857.557122143997, 9011.819763604823, 9113.635454581743, 9250.444973301623, 38490.615093848704, 39033.59420116707, 39272.0, 39438.0, 39882.20235595214, 47492.2876156851, 52230.0, 52745.47254527405, 53462.73268633632, 53544.0, 7886.171951944963, 8330.666773329, 8376.623051962799, 8393.790155246168, 8476.745697629081, 12610.49558017669, 12768.6807829805, 12935.870641293466, 12950.481980721042, 13096.607101786962, 12004.639860804189, 12191.75616487683, 12261.69345766363, 12321.630351089481, 12405.689857753267, 11688.824667629822, 11959.820602691141, 12389.0, 12426.875731242571, 13122.343882805631, 10104.595816167272, 12340.81488777651, 12375.504979800708, 12516.31160286193, 12626.810597841224, 10724.517396717323, 11024.669259922215, 11044.282121662067, 11127.944360278147, 11244.212905096565, 10326.277160598685, 10334.379937203792, 10580.15358771312, 10790.0, 11050.834237486284], "unit": "ms", "name": "yui.html", "value": 6580.346020834655}], "name": "dromaeo_css", "value": 7162.460983539879}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 103.0, 108.0, 110.0, 110.0, 100.0, 109.0, 107.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [147.0, 121.0, 154.0, 156.0, 125.0, 157.0, 156.0, 128.0, 156.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.2}, {"lowerIsBetter": true, "replicates": [161.0, 159.0, 160.0, 160.0, 160.0, 159.0, 162.0, 163.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 138.0, 72.0, 72.0, 71.0, 72.0, 149.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 86.1}, {"lowerIsBetter": true, "replicates": [88.0, 88.0, 138.0, 89.0, 88.0, 88.0, 131.0, 89.0, 89.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 102.3}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 148.0, 145.0, 146.0, 145.0, 147.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [53.0, 61.0, 55.0, 54.0, 57.0, 55.0, 57.0, 57.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.7}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 79.0, 77.0, 78.0, 80.0, 79.0, 78.0, 77.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.1}, {"lowerIsBetter": true, "replicates": [116.0, 115.0, 116.0, 104.0, 108.0, 113.0, 116.0, 113.0, 107.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.7}, {"lowerIsBetter": true, "replicates": [138.0, 138.0, 136.0, 136.0, 135.0, 134.0, 135.0, 134.0, 136.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 135.8}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 67.0, 64.0, 73.0, 63.0, 62.0, 63.0, 61.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.5}], "name": "kraken", "value": 1460.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.66666666666666], "unit": "score", "name": "Richards", "value": 211.81200000000004}, {"lowerIsBetter": false, "replicates": [170.56114617090228], "unit": "score", "name": "DeltaBlue", "value": 387.649834}, {"lowerIsBetter": false, "replicates": [279.6420581655481], "unit": "score", "name": "Encrypt", "value": 951.8632560000003}, {"lowerIsBetter": false, "replicates": [4995.024875621891], "unit": "score", "name": "Decrypt", "value": 53.289224103585696}, {"lowerIsBetter": false, "replicates": [941.6195856873823], "unit": "score", "name": "RayTrace", "value": 785.8683179999997}, {"lowerIsBetter": false, "replicates": [555.5555555555555], "unit": "score", "name": "Earley", "value": 1199.6334000000002}, {"lowerIsBetter": false, "replicates": [10416.666666666666], "unit": "score", "name": "Boyer", "value": 63.980448000000024}, {"lowerIsBetter": false, "replicates": [23880.95238095238], "unit": "score", "name": "RegExp", "value": 38.146929212362885}, {"lowerIsBetter": false, "replicates": [539.5373856912319], "unit": "score", "name": "Splay", "value": 151.0386530408773}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23256.527399752693}]} 12:15:30 INFO - RETURN: dromaeo_css: 7162.46 12:15:30 INFO - RETURN: kraken: 1460.20 12:15:30 INFO - RETURN: v8_7: 23256.53 12:15:30 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23256.53"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1460.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7162.46"}}} 12:15:30 INFO - Return code: 0 12:15:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 12:15:30 INFO - # TBPL SUCCESS # 12:15:30 INFO - Running post-action listener: _resource_record_post_action 12:15:30 INFO - Running post-run listener: _resource_record_post_run 12:15:32 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 315392; Write bytes: 502763520; Read time: 48; Write time: 467424 12:15:32 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 238006272; Read time: 0; Write time: 309704 12:15:32 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 315392; Write bytes: 263938048; Read time: 48; Write time: 157688 12:15:32 INFO - Running post-run listener: _upload_blobber_files 12:15:32 INFO - Blob upload gear active. 12:15:32 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:15:32 INFO - Copying logs to upload dir... 12:15:32 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1250.559633 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-01-04 12:15:31.844838) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-04 12:15:31.848515) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010026 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451928578/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-04 12:15:31.901365) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 12:15:31.901745) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1451937279.361083-2121311187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-04 12:15:31.953484) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 12:15:31.953844) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-04 12:15:31.954244) ========= ========= Total master_lag: 0.23 =========