builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1448372764.51 results: success (0) buildid: 20151124043011 builduid: 2efe7578203948ee9e0ca2d583784c63 revision: cf8cf1a039dd370d157b2afaa1fed719f1e34069 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.508854) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.509330) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.509623) ========= 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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006421 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.534620) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.534919) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.566575) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.566860) ========= 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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004575 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.625101) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.625381) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.625730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.626005) ========= 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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 05:46:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2015-11-24 05:46:07 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115598 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.768021) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.770467) ========= 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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021407 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.819553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 05:46:04.819883) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cf8cf1a039dd370d157b2afaa1fed719f1e34069 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cf8cf1a039dd370d157b2afaa1fed719f1e34069 --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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 05:46:07,290 truncating revision to first 12 chars 2015-11-24 05:46:07,290 Setting DEBUG logging. 2015-11-24 05:46:07,291 attempt 1/10 2015-11-24 05:46:07,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/cf8cf1a039dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 05:46:08,316 unpacking tar archive at: mozilla-inbound-cf8cf1a039dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337095 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 05:46:06.172223) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:06.172524) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:06.182549) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:06.182921) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:06.183457) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-24 05:46:06.183841) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:46:08 INFO - MultiFileLogger online at 20151124 05:46:08 in /builds/slave/test-pgo 05:46:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 05:46:08 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:46:08 INFO - {'append_to_log': False, 05:46:08 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:46:08 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:46:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:46:08 INFO - 'branch': 'Mozilla-Inbound', 05:46:08 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:08 INFO - 'config_files': ('talos/linux_config.py',), 05:46:08 INFO - 'default_actions': ('clobber', 05:46:08 INFO - 'read-buildbot-config', 05:46:08 INFO - 'download-and-extract', 05:46:08 INFO - 'populate-webroot', 05:46:08 INFO - 'create-virtualenv', 05:46:08 INFO - 'install', 05:46:08 INFO - 'run-tests'), 05:46:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:08 INFO - 'download_minidump_stackwalk': True, 05:46:08 INFO - 'download_symbols': 'ondemand', 05:46:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:08 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:08 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:08 INFO - 'installer_path': 'installer.exe', 05:46:08 INFO - 'log_level': 'info', 05:46:08 INFO - 'log_name': 'talos', 05:46:08 INFO - 'log_to_console': True, 05:46:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:46:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:46:08 INFO - 'opt_config_files': (), 05:46:08 INFO - 'pip_index': False, 05:46:08 INFO - 'sps_profile': False, 05:46:08 INFO - 'sps_profile_interval': 0, 05:46:08 INFO - 'suite': 'dromaeojs', 05:46:08 INFO - 'system_bits': '64', 05:46:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:08 INFO - 'title': 'talos-linux64-ix-061', 05:46:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:08 INFO - 'use_talos_json': True, 05:46:08 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:46:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:08 INFO - 'work_dir': 'build'} 05:46:08 INFO - ##### 05:46:08 INFO - ##### Running clobber step. 05:46:08 INFO - ##### 05:46:08 INFO - Running pre-action listener: _resource_record_pre_action 05:46:08 INFO - Running main action method: clobber 05:46:08 INFO - rmtree: /builds/slave/test-pgo/build 05:46:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:46:09 INFO - Running post-action listener: _resource_record_post_action 05:46:09 INFO - ##### 05:46:09 INFO - ##### Running read-buildbot-config step. 05:46:09 INFO - ##### 05:46:09 INFO - Running pre-action listener: _resource_record_pre_action 05:46:09 INFO - Running main action method: read_buildbot_config 05:46:09 INFO - Using buildbot properties: 05:46:09 INFO - { 05:46:09 INFO - "properties": { 05:46:09 INFO - "buildnumber": 562, 05:46:09 INFO - "product": "firefox", 05:46:09 INFO - "script_repo_revision": "production", 05:46:09 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 05:46:09 INFO - "repository": "", 05:46:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 05:46:09 INFO - "buildid": "20151124043011", 05:46:09 INFO - "slavename": "talos-linux64-ix-061", 05:46:09 INFO - "pgo_build": "True", 05:46:09 INFO - "basedir": "/builds/slave/test-pgo", 05:46:09 INFO - "project": "", 05:46:09 INFO - "platform": "ubuntu64_hw", 05:46:09 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:46:09 INFO - "slavebuilddir": "test", 05:46:09 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 05:46:09 INFO - "branch": "mozilla-inbound", 05:46:09 INFO - "repo_path": "integration/mozilla-inbound", 05:46:09 INFO - "stage_platform": "linux64-pgo", 05:46:09 INFO - "builduid": "2efe7578203948ee9e0ca2d583784c63", 05:46:09 INFO - "revision": "cf8cf1a039dd370d157b2afaa1fed719f1e34069" 05:46:09 INFO - }, 05:46:09 INFO - "sourcestamp": { 05:46:09 INFO - "repository": "", 05:46:09 INFO - "hasPatch": false, 05:46:09 INFO - "project": "", 05:46:09 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:46:09 INFO - "changes": [ 05:46:09 INFO - { 05:46:09 INFO - "category": null, 05:46:09 INFO - "files": [ 05:46:09 INFO - { 05:46:09 INFO - "url": null, 05:46:09 INFO - "name": "https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:46:09 INFO - } 05:46:09 INFO - ], 05:46:09 INFO - "repository": "", 05:46:09 INFO - "rev": "cf8cf1a039dd370d157b2afaa1fed719f1e34069", 05:46:09 INFO - "who": "sendchange", 05:46:09 INFO - "when": 1448372711, 05:46:09 INFO - "number": 6700159, 05:46:09 INFO - "comments": "", 05:46:09 INFO - "project": "", 05:46:09 INFO - "at": "Tue 24 Nov 2015 05:45:11", 05:46:09 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:46:09 INFO - "revlink": "", 05:46:09 INFO - "properties": [ 05:46:09 INFO - [ 05:46:09 INFO - "buildid", 05:46:09 INFO - "20151124043011", 05:46:09 INFO - "Change" 05:46:09 INFO - ], 05:46:09 INFO - [ 05:46:09 INFO - "builduid", 05:46:09 INFO - "2efe7578203948ee9e0ca2d583784c63", 05:46:09 INFO - "Change" 05:46:09 INFO - ], 05:46:09 INFO - [ 05:46:09 INFO - "pgo_build", 05:46:09 INFO - "True", 05:46:09 INFO - "Change" 05:46:09 INFO - ] 05:46:09 INFO - ], 05:46:09 INFO - "revision": "cf8cf1a039dd370d157b2afaa1fed719f1e34069" 05:46:09 INFO - } 05:46:09 INFO - ], 05:46:09 INFO - "revision": "cf8cf1a039dd370d157b2afaa1fed719f1e34069" 05:46:09 INFO - } 05:46:09 INFO - } 05:46:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:46:09 INFO - Running post-action listener: _resource_record_post_action 05:46:09 INFO - ##### 05:46:09 INFO - ##### Running download-and-extract step. 05:46:09 INFO - ##### 05:46:09 INFO - Running pre-action listener: _resource_record_pre_action 05:46:09 INFO - Running main action method: download_and_extract 05:46:09 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:46:09 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')]} 05:46:09 INFO - https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/test_packages.json 05:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/test_packages.json 05:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:46:11 INFO - Downloaded 1302 bytes. 05:46:11 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:46:11 INFO - Using the following test package requirements: 05:46:11 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:11 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:11 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:46:11 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:11 INFO - u'jsshell-linux-x86_64.zip'], 05:46:11 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:11 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:46:11 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:11 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:11 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:46:11 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:11 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:46:11 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:11 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:46:11 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:11 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:11 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:46:11 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:11 INFO - https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:46:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:46:12 INFO - Downloaded 22528988 bytes. 05:46:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:46:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:12 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:46:13 INFO - Return code: 0 05:46:13 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:13 INFO - https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:46:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:46:24 INFO - Downloaded 22528988 bytes. 05:46:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:46:24 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:24 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:46:25 INFO - Return code: 0 05:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:25 INFO - https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:46:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:46:26 INFO - Downloaded 11131717 bytes. 05:46:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:46:26 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:26 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:46:26 INFO - Return code: 0 05:46:26 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')]} 05:46:26 INFO - https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:46:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:46:27 INFO - Downloaded 58362354 bytes. 05:46:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:27 INFO - mkdir: /builds/slave/test-pgo/properties 05:46:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:46:27 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:46:27 INFO - Contents: 05:46:27 INFO - build_url:https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:46:27 INFO - Running post-action listener: _resource_record_post_action 05:46:27 INFO - Running post-action listener: set_extra_try_arguments 05:46:27 INFO - ##### 05:46:27 INFO - ##### Running populate-webroot step. 05:46:27 INFO - ##### 05:46:27 INFO - Running pre-action listener: _resource_record_pre_action 05:46:27 INFO - Running main action method: populate_webroot 05:46:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:27 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:46:27 INFO - '../fennec_ids.txt'], 05:46:27 INFO - 'tests': ['tcheck2']}, 05:46:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:27 INFO - '--tppagecycles', 05:46:27 INFO - '7'], 05:46:27 INFO - 'tests': ['tsvgm']}}, 05:46:27 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:46:27 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:27 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:27 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'tests': ['damp', 'tps']}, 05:46:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['damp', 'tps']}, 05:46:27 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:46:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:27 INFO - 'other': {'tests': ['a11yr', 05:46:27 INFO - 'ts_paint', 05:46:27 INFO - 'tpaint', 05:46:27 INFO - 'sessionrestore', 05:46:27 INFO - 'sessionrestore_no_auto_restore']}, 05:46:27 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['a11yr', 05:46:27 INFO - 'ts_paint', 05:46:27 INFO - 'tpaint', 05:46:27 INFO - 'sessionrestore', 05:46:27 INFO - 'sessionrestore_no_auto_restore']}, 05:46:27 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['a11yr', 05:46:27 INFO - 'ts_paint', 05:46:27 INFO - 'tpaint', 05:46:27 INFO - 'sessionrestore', 05:46:27 INFO - 'sessionrestore_no_auto_restore']}, 05:46:27 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['a11yr', 05:46:27 INFO - 'ts_paint', 05:46:27 INFO - 'tpaint', 05:46:27 INFO - 'sessionrestore', 05:46:27 INFO - 'sessionrestore_no_auto_restore']}, 05:46:27 INFO - 'other_l64': {'tests': ['a11yr', 05:46:27 INFO - 'ts_paint', 05:46:27 INFO - 'tpaint', 05:46:27 INFO - 'sessionrestore', 05:46:27 INFO - 'sessionrestore_no_auto_restore']}, 05:46:27 INFO - 'other_nol64': {'tests': ['a11yr', 05:46:27 INFO - 'ts_paint', 05:46:27 INFO - 'tpaint', 05:46:27 INFO - 'sessionrestore', 05:46:27 INFO - 'sessionrestore_no_auto_restore']}, 05:46:27 INFO - 'svgr': {'tests': ['tsvgx', 05:46:27 INFO - 'tsvgr_opacity', 05:46:27 INFO - 'tart', 05:46:27 INFO - 'tscrollx', 05:46:27 INFO - 'cart']}, 05:46:27 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['tsvgx', 05:46:27 INFO - 'tsvgr_opacity', 05:46:27 INFO - 'tart', 05:46:27 INFO - 'tscrollx', 05:46:27 INFO - 'cart']}, 05:46:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'tests': ['tp5o']}, 05:46:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'talos_options': ['--e10s'], 05:46:27 INFO - 'tests': ['tp5o']}, 05:46:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'talos_options': ['--xperf_path', 05:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:27 INFO - 'tests': ['tp5n']}, 05:46:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:27 INFO - 'talos_options': ['--e10s', 05:46:27 INFO - '--xperf_path', 05:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:27 INFO - 'tests': ['tp5n']}}, 05:46:27 INFO - 'talos.zip': {'path': '', 05:46:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:27 INFO - Running post-action listener: _resource_record_post_action 05:46:27 INFO - ##### 05:46:27 INFO - ##### Running create-virtualenv step. 05:46:27 INFO - ##### 05:46:27 INFO - Running pre-action listener: _resource_record_pre_action 05:46:27 INFO - Running main action method: create_virtualenv 05:46:27 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:46:27 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 05:46:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:46:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:27 INFO - Using real prefix '/usr' 05:46:27 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:46:28 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:30 INFO - Installing pip.................done. 05:46:30 INFO - Return code: 0 05:46:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:46:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:46:30 INFO - Using env: {'DISPLAY': ':0', 05:46:30 INFO - 'HOME': '/home/cltbld', 05:46:30 INFO - 'LANG': 'en_US.UTF-8', 05:46:30 INFO - 'LANGUAGE': 'en_US:en', 05:46:30 INFO - 'LOGNAME': 'cltbld', 05:46:30 INFO - 'MAIL': '/var/mail/cltbld', 05:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:30 INFO - 'MOZ_NO_REMOTE': '1', 05:46:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:30 INFO - 'NO_EM_RESTART': '1', 05:46:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:30 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:30 INFO - 'SHELL': '/bin/bash', 05:46:30 INFO - 'SHLVL': '1', 05:46:30 INFO - 'TERM': 'linux', 05:46:30 INFO - 'TMOUT': '86400', 05:46:30 INFO - 'USER': 'cltbld', 05:46:30 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:30 INFO - '_': '/tools/buildbot/bin/python'} 05:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:30 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 05:46:30 INFO - Cleaning up... 05:46:30 INFO - Return code: 0 05:46:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:46:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:46:30 INFO - Using env: {'DISPLAY': ':0', 05:46:30 INFO - 'HOME': '/home/cltbld', 05:46:30 INFO - 'LANG': 'en_US.UTF-8', 05:46:30 INFO - 'LANGUAGE': 'en_US:en', 05:46:30 INFO - 'LOGNAME': 'cltbld', 05:46:30 INFO - 'MAIL': '/var/mail/cltbld', 05:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:30 INFO - 'MOZ_NO_REMOTE': '1', 05:46:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:30 INFO - 'NO_EM_RESTART': '1', 05:46:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:30 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:30 INFO - 'SHELL': '/bin/bash', 05:46:30 INFO - 'SHLVL': '1', 05:46:30 INFO - 'TERM': 'linux', 05:46:30 INFO - 'TMOUT': '86400', 05:46:30 INFO - 'USER': 'cltbld', 05:46:30 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:30 INFO - '_': '/tools/buildbot/bin/python'} 05:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:30 INFO - Downloading/unpacking psutil>=0.7.1 05:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:32 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:46:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:46:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:32 INFO - Installing collected packages: psutil 05:46:32 INFO - Running setup.py install for psutil 05:46:32 INFO - building 'psutil._psutil_linux' extension 05:46:32 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 05:46:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:46:32 INFO - building 'psutil._psutil_posix' extension 05:46:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:46:33 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 05:46:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:33 INFO - Successfully installed psutil 05:46:33 INFO - Cleaning up... 05:46:33 INFO - Return code: 0 05:46:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:33 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 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:33 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 05:46:33 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 05:46:33 INFO - Using env: {'DISPLAY': ':0', 05:46:33 INFO - 'HOME': '/home/cltbld', 05:46:33 INFO - 'LANG': 'en_US.UTF-8', 05:46:33 INFO - 'LANGUAGE': 'en_US:en', 05:46:33 INFO - 'LOGNAME': 'cltbld', 05:46:33 INFO - 'MAIL': '/var/mail/cltbld', 05:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:33 INFO - 'MOZ_NO_REMOTE': '1', 05:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:33 INFO - 'NO_EM_RESTART': '1', 05:46:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:33 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:33 INFO - 'SHELL': '/bin/bash', 05:46:33 INFO - 'SHLVL': '1', 05:46:33 INFO - 'TERM': 'linux', 05:46:33 INFO - 'TMOUT': '86400', 05:46:33 INFO - 'USER': 'cltbld', 05:46:33 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:33 INFO - '_': '/tools/buildbot/bin/python'} 05:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:46:33 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 05:46:33 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 05:46:33 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 05:46:33 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 05:46:33 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 05:46:33 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 05:46:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:46:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:46:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:46:35 INFO - Installing collected packages: mozsystemmonitor 05:46:35 INFO - Running setup.py install for mozsystemmonitor 05:46:35 INFO - Successfully installed mozsystemmonitor 05:46:35 INFO - Cleaning up... 05:46:35 INFO - Return code: 0 05:46:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:46:35 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')]} 05:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:35 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')]} 05:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:35 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 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:35 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 05:46:35 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 05:46:35 INFO - Using env: {'DISPLAY': ':0', 05:46:35 INFO - 'HOME': '/home/cltbld', 05:46:35 INFO - 'LANG': 'en_US.UTF-8', 05:46:35 INFO - 'LANGUAGE': 'en_US:en', 05:46:35 INFO - 'LOGNAME': 'cltbld', 05:46:35 INFO - 'MAIL': '/var/mail/cltbld', 05:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:35 INFO - 'MOZ_NO_REMOTE': '1', 05:46:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:35 INFO - 'NO_EM_RESTART': '1', 05:46:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:35 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:35 INFO - 'SHELL': '/bin/bash', 05:46:35 INFO - 'SHLVL': '1', 05:46:35 INFO - 'TERM': 'linux', 05:46:35 INFO - 'TMOUT': '86400', 05:46:35 INFO - 'USER': 'cltbld', 05:46:35 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:35 INFO - '_': '/tools/buildbot/bin/python'} 05:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:35 INFO - Downloading/unpacking blobuploader==1.2.4 05:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:46:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:46:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:46:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:37 INFO - Downloading docopt-0.6.1.tar.gz 05:46:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:46:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:38 INFO - Running setup.py install for blobuploader 05:46:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:38 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:46:38 INFO - Running setup.py install for requests 05:46:38 INFO - Running setup.py install for docopt 05:46:38 INFO - Successfully installed blobuploader requests docopt 05:46:38 INFO - Cleaning up... 05:46:38 INFO - Return code: 0 05:46:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:38 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 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:38 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 05:46:38 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 05:46:38 INFO - Using env: {'DISPLAY': ':0', 05:46:38 INFO - 'HOME': '/home/cltbld', 05:46:38 INFO - 'LANG': 'en_US.UTF-8', 05:46:38 INFO - 'LANGUAGE': 'en_US:en', 05:46:38 INFO - 'LOGNAME': 'cltbld', 05:46:38 INFO - 'MAIL': '/var/mail/cltbld', 05:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:38 INFO - 'MOZ_NO_REMOTE': '1', 05:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:38 INFO - 'NO_EM_RESTART': '1', 05:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:38 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:38 INFO - 'SHELL': '/bin/bash', 05:46:38 INFO - 'SHLVL': '1', 05:46:38 INFO - 'TERM': 'linux', 05:46:38 INFO - 'TMOUT': '86400', 05:46:38 INFO - 'USER': 'cltbld', 05:46:38 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:38 INFO - '_': '/tools/buildbot/bin/python'} 05:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:38 INFO - Running setup.py (path:/tmp/pip-lib7tk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:38 INFO - Running setup.py (path:/tmp/pip-fq5kdJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:38 INFO - Running setup.py (path:/tmp/pip-zN9QVX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:39 INFO - Running setup.py (path:/tmp/pip-ThPicW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:39 INFO - Running setup.py (path:/tmp/pip-JtiW5P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:39 INFO - Running setup.py (path:/tmp/pip-mBnAM_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:39 INFO - Running setup.py (path:/tmp/pip-RJXwYA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:39 INFO - Running setup.py (path:/tmp/pip-V0ZgZI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:39 INFO - Running setup.py (path:/tmp/pip-u8Ovjd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:39 INFO - Running setup.py (path:/tmp/pip-LOq2CV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:39 INFO - Running setup.py (path:/tmp/pip-A9nt9G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:39 INFO - Running setup.py (path:/tmp/pip-bWXKV4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:39 INFO - Running setup.py (path:/tmp/pip-7WjWWT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:39 INFO - Running setup.py (path:/tmp/pip-4K_O3D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:39 INFO - Running setup.py (path:/tmp/pip-uKk0Lp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:39 INFO - Running setup.py (path:/tmp/pip-5d9i99-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:39 INFO - Running setup.py (path:/tmp/pip-dSZS34-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:40 INFO - Running setup.py install for manifestparser 05:46:40 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Running setup.py install for mozcrash 05:46:40 INFO - Running setup.py install for mozdebug 05:46:40 INFO - Running setup.py install for mozdevice 05:46:40 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Running setup.py install for mozfile 05:46:40 INFO - Running setup.py install for mozhttpd 05:46:40 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Running setup.py install for mozinfo 05:46:40 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Running setup.py install for mozInstall 05:46:40 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:46:40 INFO - Running setup.py install for mozleak 05:46:40 INFO - Running setup.py install for mozlog 05:46:41 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:46:41 INFO - Running setup.py install for moznetwork 05:46:41 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:46:41 INFO - Running setup.py install for mozprocess 05:46:41 INFO - Running setup.py install for mozprofile 05:46:41 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:46:41 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:46:41 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:46:41 INFO - Running setup.py install for mozrunner 05:46:41 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:46:41 INFO - Running setup.py install for mozscreenshot 05:46:41 INFO - Running setup.py install for moztest 05:46:41 INFO - Running setup.py install for mozversion 05:46:41 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:46:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:41 INFO - Cleaning up... 05:46:41 INFO - Return code: 0 05:46:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:41 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 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:41 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 05:46:41 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 05:46:41 INFO - Using env: {'DISPLAY': ':0', 05:46:41 INFO - 'HOME': '/home/cltbld', 05:46:41 INFO - 'LANG': 'en_US.UTF-8', 05:46:41 INFO - 'LANGUAGE': 'en_US:en', 05:46:41 INFO - 'LOGNAME': 'cltbld', 05:46:41 INFO - 'MAIL': '/var/mail/cltbld', 05:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:41 INFO - 'MOZ_NO_REMOTE': '1', 05:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:41 INFO - 'NO_EM_RESTART': '1', 05:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:41 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:41 INFO - 'SHELL': '/bin/bash', 05:46:41 INFO - 'SHLVL': '1', 05:46:41 INFO - 'TERM': 'linux', 05:46:41 INFO - 'TMOUT': '86400', 05:46:41 INFO - 'USER': 'cltbld', 05:46:41 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:41 INFO - '_': '/tools/buildbot/bin/python'} 05:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:42 INFO - Running setup.py (path:/tmp/pip-T2x9po-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:42 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)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:42 INFO - Running setup.py (path:/tmp/pip-SDBiGy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:42 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)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:42 INFO - Running setup.py (path:/tmp/pip-_ksLJd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:42 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)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:42 INFO - Running setup.py (path:/tmp/pip-PBu1tS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:42 INFO - Running setup.py (path:/tmp/pip-9KGD6k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:42 INFO - Running setup.py (path:/tmp/pip-EvUMc1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:42 INFO - Running setup.py (path:/tmp/pip-v4qN1B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:42 INFO - Running setup.py (path:/tmp/pip-d_28uI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:42 INFO - Running setup.py (path:/tmp/pip-EzC_Nr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:42 INFO - Running setup.py (path:/tmp/pip-du5mZr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:42 INFO - Running setup.py (path:/tmp/pip-GVvuAr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:42 INFO - Running setup.py (path:/tmp/pip-PJUvSi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:42 INFO - Running setup.py (path:/tmp/pip-bnufuc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:43 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)) 05:46:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:43 INFO - Running setup.py (path:/tmp/pip-BNaCQW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:43 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)) 05:46:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:43 INFO - Running setup.py (path:/tmp/pip-GrEj73-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:43 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)) 05:46:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:43 INFO - Running setup.py (path:/tmp/pip-HJoK0U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:43 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)) 05:46:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:43 INFO - Running setup.py (path:/tmp/pip-T4oTNQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:43 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)) 05:46:43 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)) 05:46:43 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)) 05:46:43 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)) 05:46:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:44 INFO - Downloading blessings-1.5.1.tar.gz 05:46:44 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 05:46:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:45 INFO - Installing collected packages: blessings 05:46:45 INFO - Running setup.py install for blessings 05:46:45 INFO - Successfully installed blessings 05:46:45 INFO - Cleaning up... 05:46:45 INFO - Return code: 0 05:46:45 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:46:45 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:46:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:46:45 INFO - Reading from file tmpfile_stdout 05:46:45 INFO - Current package versions: 05:46:45 INFO - argparse == 1.2.1 05:46:45 INFO - blessings == 1.5.1 05:46:45 INFO - blobuploader == 1.2.4 05:46:45 INFO - docopt == 0.6.1 05:46:45 INFO - manifestparser == 1.1 05:46:45 INFO - mozInstall == 1.12 05:46:45 INFO - mozcrash == 0.16 05:46:45 INFO - mozdebug == 0.1 05:46:45 INFO - mozdevice == 0.47 05:46:45 INFO - mozfile == 1.2 05:46:45 INFO - mozhttpd == 0.7 05:46:45 INFO - mozinfo == 0.9 05:46:45 INFO - mozleak == 0.1 05:46:45 INFO - mozlog == 3.1 05:46:45 INFO - moznetwork == 0.27 05:46:45 INFO - mozprocess == 0.22 05:46:45 INFO - mozprofile == 0.27 05:46:45 INFO - mozrunner == 6.11 05:46:45 INFO - mozscreenshot == 0.1 05:46:45 INFO - mozsystemmonitor == 0.0 05:46:45 INFO - moztest == 0.7 05:46:45 INFO - mozversion == 1.4 05:46:45 INFO - psutil == 3.1.1 05:46:45 INFO - requests == 1.2.3 05:46:45 INFO - wsgiref == 0.1.2 05:46:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46: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')]} 05:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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')]} 05:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:45 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 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:45 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 05:46:45 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 05:46:45 INFO - Using env: {'DISPLAY': ':0', 05:46:45 INFO - 'HOME': '/home/cltbld', 05:46:45 INFO - 'LANG': 'en_US.UTF-8', 05:46:45 INFO - 'LANGUAGE': 'en_US:en', 05:46:45 INFO - 'LOGNAME': 'cltbld', 05:46:45 INFO - 'MAIL': '/var/mail/cltbld', 05:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:45 INFO - 'MOZ_NO_REMOTE': '1', 05:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:45 INFO - 'NO_EM_RESTART': '1', 05:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:45 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:45 INFO - 'SHELL': '/bin/bash', 05:46:45 INFO - 'SHLVL': '1', 05:46:45 INFO - 'TERM': 'linux', 05:46:45 INFO - 'TMOUT': '86400', 05:46:45 INFO - 'USER': 'cltbld', 05:46:45 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:45 INFO - '_': '/tools/buildbot/bin/python'} 05:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:46:45 INFO - Cleaning up... 05:46:45 INFO - Return code: 0 05:46:45 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:46: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')]} 05:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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')]} 05:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:45 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 0x1efd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x212ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, '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': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', '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 05:46:45 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 05:46:45 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 05:46:45 INFO - Using env: {'DISPLAY': ':0', 05:46:45 INFO - 'HOME': '/home/cltbld', 05:46:45 INFO - 'LANG': 'en_US.UTF-8', 05:46:45 INFO - 'LANGUAGE': 'en_US:en', 05:46:45 INFO - 'LOGNAME': 'cltbld', 05:46:45 INFO - 'MAIL': '/var/mail/cltbld', 05:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:45 INFO - 'MOZ_NO_REMOTE': '1', 05:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:45 INFO - 'NO_EM_RESTART': '1', 05:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:45 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:45 INFO - 'SHELL': '/bin/bash', 05:46:45 INFO - 'SHLVL': '1', 05:46:45 INFO - 'TERM': 'linux', 05:46:45 INFO - 'TMOUT': '86400', 05:46:45 INFO - 'USER': 'cltbld', 05:46:45 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:45 INFO - '_': '/tools/buildbot/bin/python'} 05:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:45 INFO - Downloading/unpacking jsonschema 05:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:47 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 05:46:47 INFO - Downloading/unpacking functools32 (from jsonschema) 05:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:47 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:46:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:47 INFO - warning: no files found matching '*.txt' 05:46:47 INFO - no previously-included directories found matching 'build' 05:46:47 INFO - no previously-included directories found matching 'dist' 05:46:47 INFO - no previously-included directories found matching '.git*' 05:46:47 INFO - Installing collected packages: jsonschema, functools32 05:46:47 INFO - Running setup.py install for functools32 05:46:47 INFO - warning: no files found matching '*.txt' 05:46:47 INFO - no previously-included directories found matching 'build' 05:46:47 INFO - no previously-included directories found matching 'dist' 05:46:47 INFO - no previously-included directories found matching '.git*' 05:46:47 INFO - Successfully installed jsonschema functools32 05:46:47 INFO - Cleaning up... 05:46:48 INFO - Return code: 0 05:46:48 INFO - Running post-action listener: _resource_record_post_action 05:46:48 INFO - Running post-action listener: _start_resource_monitoring 05:46:48 INFO - Starting resource monitoring. 05:46:48 INFO - ##### 05:46:48 INFO - ##### Running install step. 05:46:48 INFO - ##### 05:46:48 INFO - Running pre-action listener: _resource_record_pre_action 05:46:48 INFO - Running main action method: install 05:46:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:46:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:46:48 INFO - Reading from file tmpfile_stdout 05:46:48 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:46:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:46:48 INFO - Reading from file tmpfile_stdout 05:46:48 INFO - Output received: 05:46:48 INFO - Usage: mozinstall [options] installer 05:46:48 INFO - Options: 05:46:48 INFO - -h, --help show this help message and exit 05:46:48 INFO - -d DEST, --destination=DEST 05:46:48 INFO - Directory to install application into. [default: 05:46:48 INFO - "/builds/slave/test-pgo"] 05:46:48 INFO - --app=APP Application being installed. [default: firefox] 05:46:48 INFO - mkdir: /builds/slave/test-pgo/build/application 05:46:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:46:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:47:02 INFO - Reading from file tmpfile_stdout 05:47:02 INFO - Output received: 05:47:02 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:47:02 INFO - Running post-action listener: _resource_record_post_action 05:47:02 INFO - ##### 05:47:02 INFO - ##### Running run-tests step. 05:47:02 INFO - ##### 05:47:02 INFO - Running pre-action listener: _resource_record_pre_action 05:47:02 INFO - Running main action method: run_tests 05:47:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:47:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:47:02 INFO - Python 2.7.3 05:47:02 INFO - Return code: 0 05:47:02 INFO - grabbing minidump binary from tooltool 05:47:02 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')]} 05:47:02 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 0x21b9e50>, '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 0x21bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21bb1e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:47:02 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 05:47:02 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 05:47:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:47:02 INFO - Return code: 0 05:47:02 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:47:02 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:47:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:47:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:47:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:47:02 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-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:47:02 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-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost 05:47:02 INFO - Using env: {'DISPLAY': ':0', 05:47:02 INFO - 'HOME': '/home/cltbld', 05:47:02 INFO - 'LANG': 'en_US.UTF-8', 05:47:02 INFO - 'LANGUAGE': 'en_US:en', 05:47:02 INFO - 'LOGNAME': 'cltbld', 05:47:02 INFO - 'MAIL': '/var/mail/cltbld', 05:47:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:47:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:02 INFO - 'MOZ_NO_REMOTE': '1', 05:47:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:47:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:47:02 INFO - 'NO_EM_RESTART': '1', 05:47:02 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:47:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:47:02 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:47:02 INFO - 'SHELL': '/bin/bash', 05:47:02 INFO - 'SHLVL': '1', 05:47:02 INFO - 'TERM': 'linux', 05:47:02 INFO - 'TMOUT': '86400', 05:47:02 INFO - 'USER': 'cltbld', 05:47:02 INFO - 'XDG_SESSION_COOKIE': '5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837', 05:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:02 INFO - '_': '/tools/buildbot/bin/python'} 05:47:02 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] with output_timeout 3600 05:47:03 INFO - mozversion INFO | application_buildid: 20151124043011 05:47:03 INFO - mozversion INFO | application_changeset: cf8cf1a039dd370d157b2afaa1fed719f1e34069 05:47:03 INFO - mozversion INFO | application_display_name: Nightly 05:47:03 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:47:03 INFO - mozversion INFO | application_name: Firefox 05:47:03 INFO - mozversion INFO | application_remotingname: firefox 05:47:03 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:47:03 INFO - mozversion INFO | application_vendor: Mozilla 05:47:03 INFO - mozversion INFO | application_version: 45.0a1 05:47:03 INFO - mozversion INFO | platform_buildid: 20151124043011 05:47:03 INFO - mozversion INFO | platform_changeset: cf8cf1a039dd370d157b2afaa1fed719f1e34069 05:47:03 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:47:03 INFO - mozversion INFO | platform_version: 45.0a1 05:47:03 INFO - 2015-11-24 05:47:03,186 DEBUG : using testdate: 1448372823 05:47:03 INFO - 2015-11-24 05:47:03,186 DEBUG : actual date: 1448372823 05:47:03 INFO - 2015-11-24 05:47:03,198 INFO : starting webserver on 'localhost:56916' 05:47:03 INFO - 2015-11-24 05:47:03,199 INFO : Starting test suite talos-linux64-ix-061 05:47:03 INFO - 2015-11-24 05:47:03,200 INFO : Starting test dromaeo_css 05:47:03 INFO - 2015-11-24 05:47:03,200 DEBUG : operating with platform_type : linux_ 05:47:03 INFO - 2015-11-24 05:47:03,200 INFO : Initialising browser for dromaeo_css test... 05:47:03 INFO - 2015-11-24 05:47:03,215 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8wZ1cV/profile http://localhost:56916/getInfo.html 05:47:06 INFO - 2015-11-24 05:47:06,987 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:47:06 INFO - 2015-11-24 05:47:06,987 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:47:06 INFO - 2015-11-24 05:47:06,987 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:47:06 INFO - 2015-11-24 05:47:06,987 DEBUG : BROWSER_OUTPUT: __metrics 05:47:07 INFO - 2015-11-24 05:47:07,124 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] 05:47:08 INFO - 2015-11-24 05:47:08,185 INFO : Browser initialized. 05:47:08 INFO - 2015-11-24 05:47:08,186 INFO : Running cycle 1/1 for dromaeo_css test... 05:47:08 INFO - 2015-11-24 05:47:08,186 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8wZ1cV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:47:19 INFO - 2015-11-24 05:47:19,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 139001856 05:47:19 INFO - 2015-11-24 05:47:19,945 DEBUG : BROWSER_OUTPUT: 05:48:11 INFO - 2015-11-24 05:48:11,478 DEBUG : BROWSER_OUTPUT: 1448372891475 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] 05:50:20 INFO - 2015-11-24 05:50:20,199 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56916/tests/dromaeo/cssquery-ext.html) 05:50:21 INFO - 2015-11-24 05:50:21,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 268115968 05:50:21 INFO - 2015-11-24 05:50:21,386 DEBUG : BROWSER_OUTPUT: 05:53:23 INFO - 2015-11-24 05:53:23,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-ext.html (next: http://localhost:56916/tests/dromaeo/cssquery-jquery.html) 05:53:24 INFO - 2015-11-24 05:53:24,215 DEBUG : BROWSER_OUTPUT: RSS: Main: 225153024 05:53:24 INFO - 2015-11-24 05:53:24,215 DEBUG : BROWSER_OUTPUT: 05:56:26 INFO - 2015-11-24 05:56:26,074 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56916/tests/dromaeo/cssquery-mootools.html) 05:56:26 INFO - 2015-11-24 05:56:26,655 DEBUG : BROWSER_OUTPUT: RSS: Main: 220721152 05:56:26 INFO - 2015-11-24 05:56:26,655 DEBUG : BROWSER_OUTPUT: 05:59:28 INFO - 2015-11-24 05:59:28,551 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56916/tests/dromaeo/cssquery-prototype.html) 05:59:29 INFO - 2015-11-24 05:59:29,126 DEBUG : BROWSER_OUTPUT: RSS: Main: 201818112 05:59:29 INFO - 2015-11-24 05:59:29,126 DEBUG : BROWSER_OUTPUT: 06:02:31 INFO - 2015-11-24 06:02:31,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56916/tests/dromaeo/cssquery-yui.html) 06:02:32 INFO - 2015-11-24 06:02:32,261 DEBUG : BROWSER_OUTPUT: RSS: Main: 203304960 06:02:32 INFO - 2015-11-24 06:02:32,261 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,702 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-yui.html (next: http://localhost:56916/tests/dromaeo/cssquery-dojo.html) 06:05:34 INFO - 2015-11-24 06:05:34,960 DEBUG : BROWSER_OUTPUT: RSS: Main: 279826432 06:05:34 INFO - 2015-11-24 06:05:34,960 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,961 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:05:34 INFO - 2015-11-24 06:05:34,961 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:05:34 INFO - 2015-11-24 06:05:34,961 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:05:34 INFO - 2015-11-24 06:05:34,961 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:05:34 INFO - 2015-11-24 06:05:34,961 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3171.682831716829;3221.629512606048;3263.03740396583;3268.9346213075723;3290.7202887754534;5257.763400646971;5370.758315875785;5453.127499600065;5471.1519714444275;5476.342838859333;5507.421720719327;5529.806456774014;5559.9444005559935;5577.051901176799;5636.18275350074;4750.667453278272;5050.924236136461;5056.342675452195;5085.389753229618;5108.412532558747;5110.744462776865;5117.974410127944;5164.715940623283;5188.662736922107;5193.714345710984;4858.5627293543575;4878.438979517366;4949.50504949504;4965.3048573199785;4969.577585905203;5003.799848006075;5014.24786282057;5017;5035.723035233061;5050.116229659795;4944.653874228806;4989.251612258154;5001.099802035632;5021.447640759514;5057.620678449094;5182.378114626231;5195.922061169085;5257.947420525784;5288.8413347599635;5299.125644268691;5992.2809262888295;5995.040793473067;5996.16053752475;6009.879802403927;6015.6691381974415;5793.188953546507;5996.430339117776;6019.187409699722;6022.578419510636;6023.096535519663;5458.2904222450825;5505.311836020497;5522.171674248854;5530.557555395559;5576.1356989666265;6010;6018;6035.973884439656;6072.453479186895;6074.54440916933;5576.275084239023;5814.360420353758;5819.098039803837;5821.27234095738;5882.176495290662;5823.767049317979;5862.882742345129;5871.148683440877;5951.880962380728;5955.583109182316;5948.137520059568;5982.49148822355;5986.012307969223;5992.161097446361;6023.337432882379;5984.102384642339;5984.970075149597;5989.8203053908455;5990.011648078105;5992.460678538865;4416.050549131952;4444.355568442557;4508.504064552896;4594.1271158479785;4610.05493873755;4051.47330846988;4061.654759345489;4066.9796651016554;4068.0846809468226;4099.159672267178;5546.584006199551;5582.162675598693;5592.5805564582815;5645.435456454322;5654.236678048493;5786.739596718157;5882.764689412375;5893.911591326134;5900.468957793819;6041.697915104315;5480.123180291134;5638.407967163471;5644.491995720323;5649.604527683023;5657.4908258256955;5178.352705911761;5182.352205974254;5199.636025478256;5203.323567936144;5246.108161612535;5925.911111333334;6043.395660434009;6047.667378294236;6088.665123418255;6175.228096487939;6009.36901625323;6036.486898613492;6043.667598282137;6045.304789949212;6052.788152414644;6190.226221722285;6191.721372538336;6192.287886892885;6200.472959798468;6204.3795620439405;5699.2590963174525;5747.453991870766;5771.567132465166;5824.301083869963;5833.737481813413;5885.588008839341;5913.526917846476;5914.822555323347;5923.555733319932;5958.493528049991;5793.15999180125;5794.7102644866745;5794.884102318014;5795.130730390475;5800.912986305125;5639.295088113986;5764.798232061858;5766.163906233656;5803.12953057028;5936.3470018297085;5709.22925405065;5709.34342550612;5711.171880077282;5714;5716.628419152742;5773.191753154478;5803.448672376117;5820.330661973758;5820.388859169726;5880.088586269135;5490.258815060076;5586.608937374507;5587.966226248157;5593.1889875966945;5706.686132262599 06:05:34 INFO - 2015-11-24 06:05:34,961 DEBUG : BROWSER_OUTPUT: |1;ext.html;553.219959856602;598.1565991951348;598.2282855116899;604.6855635069763;605.0772571827963;31518.369632607362;32174;32190.195245118826;32240.355192896128;32243.35513289736;33224.33551328978;42286.36570451446;43013.569864301346;43041.784791076076;43076;8669.306455483578;8787.78030549235;8789.560521973908;8794.956025219864;8799.120087991181;7639.6944122235045;7796.532208067534;8637.568121593926;8757.912420875806;8758.211760941515;7572.204918483562;10503.579856805718;10793.244472886901;10803.135749140089;10816.026557609814;10328.948355258213;10426.16590672752;10435.947820260888;10437.217208709377;10444.11225045872;7772.7668169954995;7941.404394670365;10175.542100605491;10293.786835830333;10307.84538231927;44869.32696009563;46041.53958460406;46092.76953615244;46166;46167;3304.4217261979297;3362.9159271018175;3513.508108864761;3678.1907980244305;3772.4152756322815;29382;29616.8519157405;29786.255343616365;30044.54933176004;30340.69659303401;3742.3450896093077;3758.1356288053626;3822.770633761955;3844.1350696093436;3959.98020009901;256808;257950;258361.9999999981;258738;259089;57621;57958;58214.708926455096;58233;58234.12648810271;62431.37568624256;62461;62567.6871615639;62649.686751566864;62772;15359.846401536066;15602.687946241238;15742.291596878167;15749.527514174593;15760.60598485047;10524.158067354594;10531.368117912949;10548.947255263827;10555.208359373079;10581.788364232672;19142.7557208781;20130.798692013184;20240.392788216377;20301.187952481734;20321.89839050795;7943.64253608589;7995.600219988977;8094.271515563628;8106.918930810616;8158.102608713037;972.5526257921435;1015.3197357770312;1050.847627094067;1058.7935352606169;1086.9130469562417;25084.74915250824;25537.48925021489;25731.871340643553;26472.735272647027;27894.44211115807;1003.2174903575223;1026.6919924022764;1069.1820757120809;1104.3539529375246;1108.141190577293;14331.211783352015;14485.130892146504;14574.927125364306;14696.706065878836;14723.337449814784;308943;309313;309759.0240975929;324205;331866;50120.49879501231;52030.73984630129;52201.2169817453;52262;52402.47597523976;1336.4052996416501;1352.0805852020524;1360.333436616051;1360.8026836108907;1364.0724307471014;24412.75587244105;24593.50812983766;28392.574111387934;28598.428031438838;28845.711542884303;43905.78047109872;44410.555894440644;44501.554984449744;44591;44595.55404445914;225819;225831;227364;228240;228273;31861.36277274488;31874.84062579672;32784.67215327816;32941.34117317688;32963.175920601214;40649.1870162601;41060.79469602633;42689;42727.3590896143;42900.57099428966;33478.83260583682;33857;33902.32195356128;33925.83037084799;34742;35378.292434151685;36886.44670330001;37672.246555069294;37848;38036.42945355766;35657.46513802347;36574.817125914204;36577.268454630226;36619.63380366162;36775.44836827396;30434.54348184823;33082.83458582788;33546.161345966546;33553.49669754907;34023.31953360968;24692;25168.548250970463;25379.238622841345;25545.74454255508;25931.092411765498 06:05:34 INFO - 2015-11-24 06:05:34,962 DEBUG : BROWSER_OUTPUT: |2;jquery.html;353.2951761236334;356.0138416586058;357.11079412263473;360.41972424396715;362.0983750461351;14425.42298308069;14427.134371937665;16598.585035374123;17743;19006.809931900694;10704.571817127326;10983.670489885297;16036.518904432856;16524.339026438927;16525.504234872944;4720.881977950553;4846.200376937801;4848.224284114543;4848.87877803056;4849.51504849514;3681.9631803682023;3768.2275133597545;4955.653104282702;4985.476524964881;5227.21591761237;5314.813981510648;5333.253344531769;5349.5185433311;5352.116900711398;5357.223202635615;5061.949380506203;5218.582513398919;5223.1642937130255;5326.307580014612;5328.946710532903;4952.281919121707;4981.078500477423;5040.319556859814;5044.369453818273;5048.747562621854;11934.820977685342;12149.574764883188;12155.635330940086;12160.635180944497;16587.83412165875;2011.5071807407132;2079.750429948401;2093.905774240163;2117.7776333484917;2123.50097727034;9553.522323883779;10075.848862267072;10483.161347092215;10546.578136874516;13361.933190334083;1862.8602854785945;1879.5583037986062;1889;1904.1050706168078;1937.3703546347494;60105.6994715028;63067.6846615764;63129.684351578406;63528;63552;10132.645357412455;10244.846327305097;10312.381257124596;10316.226283028804;10405.47972601367;13545.932270338782;13590.32048397596;13628.114172578751;13632.522861699794;13650.453981840814;7631.503952243086;7681;7697.23027697237;7721.65252563636;7748.263914928073;7511.549307041595;7512.887306690404;7542;7582.431317651088;7588.317051465395;4342.522322544518;4378.912421751547;4390.165868484977;4461.24158892989;4511.390962220058;1578.4948816378649;1651.9421820236475;1698.0915210362398;1795.6678014567347;1832.1755210155484;924.8612708093705;940.5438362394233;959.8608201810838;974.1086905481546;995.2237254941158;14643.92678036603;15899.28453219608;16910.239039243264;17348.30606775715;17685.646287074185;842.5492361586645;893.7363477774072;957.4351132831523;962.3407965543626;999.4253304350028;9385.779848619635;9610.90389096114;9659.420434773936;9675.564599592895;9744.853827192597;557418;558552;560497;587743;588925;78959.21040789633;81337.18662813296;81691.18308816836;82172.58913705393;82347.58826205951;1054.478033373485;1055.5355643516982;1073.9677809665723;1083.631565267797;1083.7128161037174;15080.396784128512;15353.923230383776;15399;15402.537923862303;15407.460738874086;34883.47674784931;35120.29759404849;35334.46998294976;35690.46464302985;35990.28019439544;45343.31985020293;46036.07927841358;46702;47097;47149.76425117852;9024.187823095954;9440.527973601429;9495.572699228413;9496.525173741424;9498.95250523743;10836.945815270874;13974.301284935917;16845.578860528094;17228.310867565157;17308.480745577654;13853.099548529313;14027.088239264414;14102.36539355752;14550.4907328243;14667.853321467075;13963.30183490801;14116.929415352857;14174.929125354307;14361.281935902955;14520.782188267396;11777.470013849226;14253;14524.273786310854;14929.402823886923;15038.62403439879;9680.75798105053;9702.369345992487;9806.754831128994;9938.055884690943;9938.403695778277;13792.172469651852;14652.706945861237;14731.558053258419;15030.098194108388;15279.694406112038 06:05:34 INFO - 2015-11-24 06:05:34,965 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1582.69137518184;1671.8746094042947;1747.5001870184278;1756.350150444336;1799.2173404569005;6599.142111525501;6758.932410675891;7543.471956963015;7660.540367577949;7680.155182929873;7705.49914255573;7716.729914452995;7780.183080776522;7798.2201779822135;7800.258975397328;1910.646530391875;1952.570434504414;2199.604071267171;2212.9446763830874;2216.5788500184995;2334.2997100869757;2361.988190059047;2363.3382652857226;2369.656399822025;2371.1937941100023;2285.4172186092524;2286.9937227619794;2340.6489026646054;2346.4368551547677;2348.988255058722;1981.9900900495477;2008.1365013044385;2239.496113374494;2295.070496448941;2318;2290.81673466124;2298.9195378161853;2306.1351993002622;2306.919257825968;2308.4690521180223;7672.271134242238;7785.688572457095;7790.220977902221;7792.142864284924;7802.33680137189;4732.266498692674;4793.904121917542;4796.5683088521855;4799.0401919616215;4836.782344794493;3139.8587063582195;3144.5440411140257;3182.6339970903177;3217.3565286942703;3266.7549933754826;989.8267803134495;1001.0940099210227;1004.091297375876;1024.482636268687;1044.5247412427327;10636;10636.574537018587;10673.252872298943;10693.251472396936;10694.144468442506;4252.107057517956;4368.6068253856665;4370.431843860341;4433.645308375322;4510.571495707903;9043.502607356528;9133.543322833964;9152.8627070594;9153.77115572116;9156.175944165057;4726.598239149711;4748.525147485224;4765.833195838129;4767.332573439721;4778.737169455644;4308.224519586442;4310.762908040025;4315.201687687725;4317.266064768997;4320.503142138631;3730.477733117366;3751.2309976454762;3753.8123093845743;3775.4714339992424;3793.677537409296;1243.0801207106676;1245.862955074941;1249.6688377579949;1252.874712528758;1259.376608578741;1343.6372179511477;1344.8184495093037;1359.57853065548;1377.3457607636492;1380.1718968618748;6440.198840418072;6677.465802735866;7414.369778568882;7578.052743407075;7600.619969001638;607.7143742441045;616.488314698799;622.1507642068544;637.4708991537013;641.5573254454505;5782.190493330937;5784.566157538202;5801.6809075500405;5807.245058142415;5839.0949402842625;9275.67535136267;9381.718548443558;9392.34253602255;9564.808703825884;9568.378055426376;9124.087591240956;9282.535873206312;9362.531873406302;9422.434653920787;9435.528223588793;7157.713691452388;7201.603911784798;7230.2046774854725;7283.927160728324;7297;6873.656317184021;7067.893981590383;7085.220625731268;7090.113735783028;7095.432365410651;7992.6802927884555;8133.877991830245;8147.307478864363;8279.047909490486;8299.377546684143;8125.431219814793;8291.95854020726;8323.12607176262;8460.746177614681;8469.534175620154;6789.93210067893;6855.383015528626;6861.725530978705;6867.759628412981;6869.828254293483;7516.849663006818;7571.674956882413;7577.886331705139;7594.088709354913;7610.1628820828555;7038.3665470107935;7042.7887163385185;7155.427565794828;7194.388476979306;7196.208417074013;6995.580265184105;7025.68384422692;7033.261507541839;7120.501564890408;7141.392981596622;6083.452489275986;6208.937910620836;7134.286571342825;7170.749023784142;7182.245864184186;6408.743650254124;6488.286288508355;6529.575577587441;6537.771178008747;6552.803415897531;6508.056331831953;6546.443552298108;6576.790853853633;6583.506237032146;6625.701843416961 06:05:34 INFO - 2015-11-24 06:05:34,966 DEBUG : BROWSER_OUTPUT: |4;prototype.html;337.1587888218893;338.5362053986039;340.88921100642295;343.7250199840127;345.89968909016386;5822.854428639275;5868.50117739992;5872.119182122684;5913.64518128912;5994.340622531518;5794.159846822208;5886.264216972879;5899;5910.940890591093;5914.970425147879;4594.104149690812;4609.585137337639;4609.815607375701;4610.377599024139;4610.792514336855;4410.228210063242;4481.282994720846;4509.616682581985;4544.545545445446;4600.5629465200755;4498.707584007029;4505.076459325846;4581.129585378767;4581.152486789954;4583.083383323332;4559;4560.950981274309;4561.133384656904;4581.671315318554;4582.908341833178;4006.1186538961383;4035.1929614077007;4042.312806822818;4043.4541336919433;4050.878473645766;5476.945230547723;5607.579431542651;5617.522510586605;5667.3482549506925;5688.203651488795;5263.210518422229;5263.710495922723;5279.41926388097;5279.736013199325;5288.127458969266;5988.981873081587;6308.3060863305;6311.6213027218055;6318.557700960934;6323.525735569805;4272.636825869805;4297.39836422901;4384.254676704968;4386.245501799274;4390.8902277443;6468.74125034998;6571.2771595124495;6573.967130164318;6574.835629109262;6581.407673309377;4415.072834704683;4419.292913133948;4422.425084739028;4422.624076953495;4431.423914891044;4394.494633117232;4480.484744254387;4482.170798402306;4509.549045095464;4565.908681826345;5258.158694608921;5258.50044245796;5259.15853463444;5263.052650522943;5264.42091369949;6347.079673447324;6360.936390636127;6361.300256971729;6362.427381535684;6368.235811702532;1595.9601009974845;1614.7254966655696;1615.3780794394008;1615.6122530592572;1615.9272832722556;1063.8936106389299;1064.3986147826608;1072.7961687279392;1074.5003573338279;1096.681962230944;6094.481969032593;6161.815145545641;6173.197484327098;6184.8763024739255;6190.876182476325;6566.967165164239;6593.439557637558;6593.934060659332;6594.07682924391;6595.637239951753;6019.428154325332;6116.938830611637;6172.259328880563;6183.628982261079;6186.505079593621;3539.5752509699;3556.7688100273917;3557.3774589446743;3561.3233485637647;3563.625819288989;6784.762533311409;6785.084013658192;6788.626625535643;6792.524523283323;6795.2185498667295;6699.598024118569;6704.631245281556;6708.429783468362;6718.596884186964;6719.193696756421;6639.43564796988;6723.831904202336;6746.628935408599;6915.582305627334;6919.37725604698;6366.745330186926;6526.477881769542;6635.071090047494;6640.667966601747;6652.301508341748;6456.741730330734;6562;6587.4071333580205;6596.670166491752;6599.60402375859;6887.104676392238;6891.138607674041;6892.862142757217;6899.310068993061;6914.274001229798;4405.118976204836;4462.576055274743;4505.301678239747;4507.346434766875;4517.661175411923;6579.802605921638;6596.7031483584315;6606.372394622503;6612.702428390638;6627.966860165861;6437.195350581177;6442.581232219891;6442.774502892376;6452.9677351612945;6455.935440645533;6242.687865606611;6380.202474690664;6384.521160912857;6386.744530218925;6405.743770249138;6266.122742816101;6271.090691849748;6273.686315684289;6275.3724627536885;6282.937170628418;6418.4865210783955;6421.903671444838;6424.100625912282;6428.967855160881;6443;6105;6292.528060395397;6294.181756371642;6299.6535190564955;6302.086197501245 06:05:34 INFO - 2015-11-24 06:05:34,966 DEBUG : BROWSER_OUTPUT: |5;yui.html;719.74089327842;728.9416846652268;753.9623018849056;770.6146926536732;844.8183640517287;13699.246541440229;13892.51376201832;13897.513587024456;13916.373763180656;13925.09486883352;13260.46958121674;13378.13042152262;13638.181709097462;13707.588772336821;13719.725605487909;805.2551389964289;828.6561077153004;829.5852073963018;831.2103501673404;831.3515457942784;814.975550733476;821.7411515372638;827.7930517370658;828.3994104274383;829.4401279136558;850.0224741547239;852.6461518469828;860.7718954477068;865.5931712095337;865.68835219321;854.487307615432;860.27736701171;863.1713554987189;865.1737590600961;865.3682811547606;626.6177631644694;629.3894921925724;631.6368088349171;633.6578247746212;634.191405957408;12749.681257968532;12780.297083660394;12940.35298235094;12943.093983421164;12952.676183095404;10805.243632945698;11125.443727813657;11139.554417823278;11298.830517542243;11523.48144333507;9831.213502919749;9847.950760246153;9905;10025.147862431702;10541;3612.837422316002;3689.612590677994;3702.5186725725484;3702.5371828521434;3785.0915780212827;40418.39372409416;41234.58765412367;41826.581734182575;45429.54570454286;50675;9844.950775246078;9900.950495247478;9907.3560218587;9953.452560109263;10148.492575371201;17563.38528151534;17642.647147057243;17642.823571764377;17654.382096626556;17663.64672706539;7419.07261592301;7438.702451901863;7439.962800186073;7629.237076292437;7834;4327.459067616548;4338.978305108454;4388.363687265329;4390.758508282075;4515.954840451619;3049.268175637831;3102.612173478315;3104.425681248976;3106.6116735408073;3107.829069401205;562.5499600319728;566;566.4052744618134;567.6650776042072;581.4505292498633;621.586644881149;648.9091527186198;652.7584793626388;667.6361383046251;693.9236683964759;8789.882006635542;8805.515696636618;8828.293736501064;8913.242374398249;8967.910320896708;631.9936800632026;640.8782331357028;658.8385845467891;672.3377473188905;679.864027194563;3826.7129965252716;4370.584794444522;4408.801603927831;4428.446444194476;4520.344550040226;51932.48067519352;53298.20052699213;53407.19889201665;53497;53534.73232633812;46090.308645369674;48463.5153648459;48598;51216.743916280924;53451.732741336826;42767.144657106684;46994;50512.242316365286;50533;50804.745976269885;8680.521030128168;8854;9189.310801689766;9223;9258.166764990914;36296;36991.81504092462;37136.25727485489;37359.25281494409;37531.43702844405;41967.370489443296;42113;42617.78691106525;42714.5728542723;47958.76020619875;10597.629082982367;10600.455890586358;10839.458027098772;10900.672979810619;10927;11981.700457488632;12158.45286962071;12217.266963982189;12252.938735306267;12643.810342844681;11357.886421136014;11610.593629222936;11686.94156529212;11983.221090629082;12021.218620789621;11227.326360418401;11350.432478376213;11359.54561817551;11492;11800.645980620595;11481.712957175803;11499;11537.48081336325;11938.820917686067;11950.940245298718;9552;9693.127618514085;9800.166985806287;10116.393016419039;10228.590856365661;9158.58786354629;9249.491277979776;9277.350585459222;9633.150687656036;9992.65025724096 06:05:34 INFO - 2015-11-24 06:05:34,966 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,964 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: __startTimestamp1448373934954__endTimestamp 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,967 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5495.40 Median:5706.69 stddev:626.24 (11.0%) stddev-sans-first:601.04 06:05:34 INFO - 2015-11-24 06:05:34,968 DEBUG : BROWSER_OUTPUT: Values: 3171.7 3221.6 3263.0 3268.9 3290.7 5257.8 5370.8 5453.1 5471.2 5476.3 5507.4 5529.8 5559.9 5577.1 5636.2 4750.7 5050.9 5056.3 5085.4 5108.4 5110.7 5118.0 5164.7 5188.7 5193.7 4858.6 4878.4 4949.5 4965.3 4969.6 5003.8 5014.2 5017.0 5035.7 5050.1 4944.7 4989.3 5001.1 5021.4 5057.6 5182.4 5195.9 5257.9 5288.8 5299.1 5992.3 5995.0 5996.2 6009.9 6015.7 5793.2 5996.4 6019.2 6022.6 6023.1 5458.3 5505.3 5522.2 5530.6 5576.1 6010.0 6018.0 6036.0 6072.5 6074.5 5576.3 5814.4 5819.1 5821.3 5882.2 5823.8 5862.9 5871.1 5951.9 5955.6 5948.1 5982.5 5986.0 5992.2 6023.3 5984.1 5985.0 5989.8 5990.0 5992.5 4416.1 4444.4 4508.5 4594.1 4610.1 4051.5 4061.7 4067.0 4068.1 4099.2 5546.6 5582.2 5592.6 5645.4 5654.2 5786.7 5882.8 5893.9 5900.5 6041.7 5480.1 5638.4 5644.5 5649.6 5657.5 5178.4 5182.4 5199.6 5203.3 5246.1 5925.9 6043.4 6047.7 6088.7 6175.2 6009.4 6036.5 6043.7 6045.3 6052.8 6190.2 6191.7 6192.3 6200.5 6204.4 5699.3 5747.5 5771.6 5824.3 5833.7 5885.6 5913.5 5914.8 5923.6 5958.5 5793.2 5794.7 5794.9 5795.1 5800.9 5639.3 5764.8 5766.2 5803.1 5936.3 5709.2 5709.3 5711.2 5714.0 5716.6 5773.2 5803.4 5820.3 5820.4 5880.1 5490.3 5586.6 5588.0 5593.2 5706.7 06:05:34 INFO - 2015-11-24 06:05:34,968 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,969 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44117.88 Median:18133.80 stddev:70439.68 (388.4%) stddev-sans-first:70561.33 06:05:34 INFO - 2015-11-24 06:05:34,969 DEBUG : BROWSER_OUTPUT: Values: 553.2 598.2 598.2 604.7 605.1 31518.4 32174.0 32190.2 32240.4 32243.4 33224.3 42286.4 43013.6 43041.8 43076.0 8669.3 8787.8 8789.6 8795.0 8799.1 7639.7 7796.5 8637.6 8757.9 8758.2 7572.2 10503.6 10793.2 10803.1 10816.0 10328.9 10426.2 10435.9 10437.2 10444.1 7772.8 7941.4 10175.5 10293.8 10307.8 44869.3 46041.5 46092.8 46166.0 46167.0 3304.4 3362.9 3513.5 3678.2 3772.4 29382.0 29616.9 29786.3 30044.5 30340.7 3742.3 3758.1 3822.8 3844.1 3960.0 256808.0 257950.0 258362.0 258738.0 259089.0 57621.0 57958.0 58214.7 58233.0 58234.1 62431.4 62461.0 62567.7 62649.7 62772.0 15359.8 15602.7 15742.3 15749.5 15760.6 10524.2 10531.4 10548.9 10555.2 10581.8 19142.8 20130.8 20240.4 20301.2 20321.9 7943.6 7995.6 8094.3 8106.9 8158.1 972.6 1015.3 1050.8 1058.8 1086.9 25084.7 25537.5 25731.9 26472.7 27894.4 1003.2 1026.7 1069.2 1104.4 1108.1 14331.2 14485.1 14574.9 14696.7 14723.3 308943.0 309313.0 309759.0 324205.0 331866.0 50120.5 52030.7 52201.2 52262.0 52402.5 1336.4 1352.1 1360.3 1360.8 1364.1 24412.8 24593.5 28392.6 28598.4 28845.7 43905.8 44410.6 44501.6 44591.0 44595.6 225819.0 225831.0 227364.0 228240.0 228273.0 31861.4 31874.8 32784.7 32941.3 32963.2 40649.2 41060.8 42689.0 42727.4 42900.6 33478.8 33857.0 33902.3 33925.8 34742.0 35378.3 36886.4 37672.2 37848.0 38036.4 35657.5 36574.8 36577.3 36619.6 36775.4 30434.5 33082.8 33546.2 33553.5 34023.3 24692.0 25168.5 25379.2 25545.7 25931.1 06:05:34 INFO - 2015-11-24 06:05:34,969 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,969 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29486.66 Median:18173.70 stddev:93304.17 (513.4%) stddev-sans-first:93540.27 06:05:34 INFO - 2015-11-24 06:05:34,969 DEBUG : BROWSER_OUTPUT: Values: 353.3 356.0 357.1 360.4 362.1 14425.4 14427.1 16598.6 17743.0 19006.8 10704.6 10983.7 16036.5 16524.3 16525.5 4720.9 4846.2 4848.2 4848.9 4849.5 3682.0 3768.2 4955.7 4985.5 5227.2 5314.8 5333.3 5349.5 5352.1 5357.2 5061.9 5218.6 5223.2 5326.3 5328.9 4952.3 4981.1 5040.3 5044.4 5048.7 11934.8 12149.6 12155.6 12160.6 16587.8 2011.5 2079.8 2093.9 2117.8 2123.5 9553.5 10075.8 10483.2 10546.6 13361.9 1862.9 1879.6 1889.0 1904.1 1937.4 60105.7 63067.7 63129.7 63528.0 63552.0 10132.6 10244.8 10312.4 10316.2 10405.5 13545.9 13590.3 13628.1 13632.5 13650.5 7631.5 7681.0 7697.2 7721.7 7748.3 7511.5 7512.9 7542.0 7582.4 7588.3 4342.5 4378.9 4390.2 4461.2 4511.4 1578.5 1651.9 1698.1 1795.7 1832.2 924.9 940.5 959.9 974.1 995.2 14643.9 15899.3 16910.2 17348.3 17685.6 842.5 893.7 957.4 962.3 999.4 9385.8 9610.9 9659.4 9675.6 9744.9 557418.0 558552.0 560497.0 587743.0 588925.0 78959.2 81337.2 81691.2 82172.6 82347.6 1054.5 1055.5 1074.0 1083.6 1083.7 15080.4 15353.9 15399.0 15402.5 15407.5 34883.5 35120.3 35334.5 35690.5 35990.3 45343.3 46036.1 46702.0 47097.0 47149.8 9024.2 9440.5 9495.6 9496.5 9499.0 10836.9 13974.3 16845.6 17228.3 17308.5 13853.1 14027.1 14102.4 14550.5 14667.9 13963.3 14116.9 14174.9 14361.3 14520.8 11777.5 14253.0 14524.3 14929.4 15038.6 9680.8 9702.4 9806.8 9938.1 9938.4 13792.2 14652.7 14731.6 15030.1 15279.7 06:05:34 INFO - 2015-11-24 06:05:34,969 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,969 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5442.02 Median:3525.15 stddev:2799.92 (79.4%) stddev-sans-first:2792.75 06:05:34 INFO - 2015-11-24 06:05:34,970 DEBUG : BROWSER_OUTPUT: Values: 1582.7 1671.9 1747.5 1756.4 1799.2 6599.1 6758.9 7543.5 7660.5 7680.2 7705.5 7716.7 7780.2 7798.2 7800.3 1910.6 1952.6 2199.6 2212.9 2216.6 2334.3 2362.0 2363.3 2369.7 2371.2 2285.4 2287.0 2340.6 2346.4 2349.0 1982.0 2008.1 2239.5 2295.1 2318.0 2290.8 2298.9 2306.1 2306.9 2308.5 7672.3 7785.7 7790.2 7792.1 7802.3 4732.3 4793.9 4796.6 4799.0 4836.8 3139.9 3144.5 3182.6 3217.4 3266.8 989.8 1001.1 1004.1 1024.5 1044.5 10636.0 10636.6 10673.3 10693.3 10694.1 4252.1 4368.6 4370.4 4433.6 4510.6 9043.5 9133.5 9152.9 9153.8 9156.2 4726.6 4748.5 4765.8 4767.3 4778.7 4308.2 4310.8 4315.2 4317.3 4320.5 3730.5 3751.2 3753.8 3775.5 3793.7 1243.1 1245.9 1249.7 1252.9 1259.4 1343.6 1344.8 1359.6 1377.3 1380.2 6440.2 6677.5 7414.4 7578.1 7600.6 607.7 616.5 622.2 637.5 641.6 5782.2 5784.6 5801.7 5807.2 5839.1 9275.7 9381.7 9392.3 9564.8 9568.4 9124.1 9282.5 9362.5 9422.4 9435.5 7157.7 7201.6 7230.2 7283.9 7297.0 6873.7 7067.9 7085.2 7090.1 7095.4 7992.7 8133.9 8147.3 8279.0 8299.4 8125.4 8292.0 8323.1 8460.7 8469.5 6789.9 6855.4 6861.7 6867.8 6869.8 7516.8 7571.7 7577.9 7594.1 7610.2 7038.4 7042.8 7155.4 7194.4 7196.2 6995.6 7025.7 7033.3 7120.5 7141.4 6083.5 6208.9 7134.3 7170.7 7182.2 6408.7 6488.3 6529.6 6537.8 6552.8 6508.1 6546.4 6576.8 6583.5 6625.7 06:05:34 INFO - 2015-11-24 06:05:34,970 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,970 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5312.26 Median:6006.88 stddev:1611.04 (26.8%) stddev-sans-first:1571.69 06:05:34 INFO - 2015-11-24 06:05:34,970 DEBUG : BROWSER_OUTPUT: Values: 337.2 338.5 340.9 343.7 345.9 5822.9 5868.5 5872.1 5913.6 5994.3 5794.2 5886.3 5899.0 5910.9 5915.0 4594.1 4609.6 4609.8 4610.4 4610.8 4410.2 4481.3 4509.6 4544.5 4600.6 4498.7 4505.1 4581.1 4581.2 4583.1 4559.0 4561.0 4561.1 4581.7 4582.9 4006.1 4035.2 4042.3 4043.5 4050.9 5476.9 5607.6 5617.5 5667.3 5688.2 5263.2 5263.7 5279.4 5279.7 5288.1 5989.0 6308.3 6311.6 6318.6 6323.5 4272.6 4297.4 4384.3 4386.2 4390.9 6468.7 6571.3 6574.0 6574.8 6581.4 4415.1 4419.3 4422.4 4422.6 4431.4 4394.5 4480.5 4482.2 4509.5 4565.9 5258.2 5258.5 5259.2 5263.1 5264.4 6347.1 6360.9 6361.3 6362.4 6368.2 1596.0 1614.7 1615.4 1615.6 1615.9 1063.9 1064.4 1072.8 1074.5 1096.7 6094.5 6161.8 6173.2 6184.9 6190.9 6567.0 6593.4 6593.9 6594.1 6595.6 6019.4 6116.9 6172.3 6183.6 6186.5 3539.6 3556.8 3557.4 3561.3 3563.6 6784.8 6785.1 6788.6 6792.5 6795.2 6699.6 6704.6 6708.4 6718.6 6719.2 6639.4 6723.8 6746.6 6915.6 6919.4 6366.7 6526.5 6635.1 6640.7 6652.3 6456.7 6562.0 6587.4 6596.7 6599.6 6887.1 6891.1 6892.9 6899.3 6914.3 4405.1 4462.6 4505.3 4507.3 4517.7 6579.8 6596.7 6606.4 6612.7 6628.0 6437.2 6442.6 6442.8 6453.0 6455.9 6242.7 6380.2 6384.5 6386.7 6405.7 6266.1 6271.1 6273.7 6275.4 6282.9 6418.5 6421.9 6424.1 6429.0 6443.0 6105.0 6292.5 6294.2 6299.7 6302.1 06:05:34 INFO - 2015-11-24 06:05:34,971 DEBUG : BROWSER_OUTPUT: 06:05:34 INFO - 2015-11-24 06:05:34,971 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13593.58 Median:47476.38 stddev:15418.22 (32.5%) stddev-sans-first:15431.16 06:05:34 INFO - 2015-11-24 06:05:34,972 DEBUG : BROWSER_OUTPUT: Values: 719.7 728.9 754.0 770.6 844.8 13699.2 13892.5 13897.5 13916.4 13925.1 13260.5 13378.1 13638.2 13707.6 13719.7 805.3 828.7 829.6 831.2 831.4 815.0 821.7 827.8 828.4 829.4 850.0 852.6 860.8 865.6 865.7 854.5 860.3 863.2 865.2 865.4 626.6 629.4 631.6 633.7 634.2 12749.7 12780.3 12940.4 12943.1 12952.7 10805.2 11125.4 11139.6 11298.8 11523.5 9831.2 9848.0 9905.0 10025.1 10541.0 3612.8 3689.6 3702.5 3702.5 3785.1 40418.4 41234.6 41826.6 45429.5 50675.0 9845.0 9901.0 9907.4 9953.5 10148.5 17563.4 17642.6 17642.8 17654.4 17663.6 7419.1 7438.7 7440.0 7629.2 7834.0 4327.5 4339.0 4388.4 4390.8 4516.0 3049.3 3102.6 3104.4 3106.6 3107.8 562.5 566.0 566.4 567.7 581.5 621.6 648.9 652.8 667.6 693.9 8789.9 8805.5 8828.3 8913.2 8967.9 632.0 640.9 658.8 672.3 679.9 3826.7 4370.6 4408.8 4428.4 4520.3 51932.5 53298.2 53407.2 53497.0 53534.7 46090.3 48463.5 48598.0 51216.7 53451.7 42767.1 46994.0 50512.2 50533.0 50804.7 8680.5 8854.0 9189.3 9223.0 9258.2 36296.0 36991.8 37136.3 37359.3 37531.4 41967.4 42113.0 42617.8 42714.6 47958.8 10597.6 10600.5 10839.5 10900.7 10927.0 11981.7 12158.5 12217.3 12252.9 12643.8 11357.9 11610.6 11686.9 11983.2 12021.2 11227.3 11350.4 11359.5 11492.0 11800.6 11481.7 11499.0 11537.5 11938.8 11950.9 9552.0 9693.1 9800.2 10116.4 10228.6 9158.6 9249.5 9277.4 9633.2 9992.7 06:05:34 INFO - 2015-11-24 06:05:34,972 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:05:34 INFO - 2015-11-24 06:05:34,972 DEBUG : BROWSER_OUTPUT: 06:05:35 INFO - 2015-11-24 06:05:35,601 INFO : Browser exited with error code: 0 06:05:35 INFO - 2015-11-24 06:05:35,620 INFO : Completed test dromaeo_css (00:18:32) 06:05:35 INFO - 2015-11-24 06:05:35,620 INFO : Starting test kraken 06:05:35 INFO - 2015-11-24 06:05:35,620 DEBUG : operating with platform_type : linux_ 06:05:35 INFO - 2015-11-24 06:05:35,620 INFO : Initialising browser for kraken test... 06:05:35 INFO - 2015-11-24 06:05:35,626 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEsUgfh/profile http://localhost:56916/getInfo.html 06:05:39 INFO - 2015-11-24 06:05:39,199 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:05:39 INFO - 2015-11-24 06:05:39,199 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:05:39 INFO - 2015-11-24 06:05:39,199 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:05:39 INFO - 2015-11-24 06:05:39,199 DEBUG : BROWSER_OUTPUT: __metrics 06:05:39 INFO - 2015-11-24 06:05:39,280 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] 06:05:40 INFO - 2015-11-24 06:05:40,597 INFO : Browser initialized. 06:05:40 INFO - 2015-11-24 06:05:40,597 INFO : Running cycle 1/1 for kraken test... 06:05:40 INFO - 2015-11-24 06:05:40,598 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEsUgfh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:05:52 INFO - 2015-11-24 06:05:52,339 DEBUG : BROWSER_OUTPUT: RSS: Main: 139145216 06:05:52 INFO - 2015-11-24 06:05:52,339 DEBUG : BROWSER_OUTPUT: 06:05:54 INFO - 2015-11-24 06:05:54,794 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:55 INFO - 2015-11-24 06:05:55,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 177971200 06:05:55 INFO - 2015-11-24 06:05:55,369 DEBUG : BROWSER_OUTPUT: 06:05:57 INFO - 2015-11-24 06:05:57,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-dft) 06:05:58 INFO - 2015-11-24 06:05:58,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 165511168 06:05:58 INFO - 2015-11-24 06:05:58,532 DEBUG : BROWSER_OUTPUT: 06:06:01 INFO - 2015-11-24 06:06:01,371 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-fft) 06:06:01 INFO - 2015-11-24 06:06:01,932 DEBUG : BROWSER_OUTPUT: RSS: Main: 150663168 06:06:01 INFO - 2015-11-24 06:06:01,932 DEBUG : BROWSER_OUTPUT: 06:06:03 INFO - 2015-11-24 06:06:03,897 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-oscillator) 06:06:04 INFO - 2015-11-24 06:06:04,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 153665536 06:06:04 INFO - 2015-11-24 06:06:04,466 DEBUG : BROWSER_OUTPUT: 06:06:06 INFO - 2015-11-24 06:06:06,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56916/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:06:07 INFO - 2015-11-24 06:06:07,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 154750976 06:06:07 INFO - 2015-11-24 06:06:07,233 DEBUG : BROWSER_OUTPUT: 06:06:10 INFO - 2015-11-24 06:06:10,112 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56916/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:10 INFO - 2015-11-24 06:06:10,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 155922432 06:06:10 INFO - 2015-11-24 06:06:10,681 DEBUG : BROWSER_OUTPUT: 06:06:14 INFO - 2015-11-24 06:06:14,049 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56916/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:14 INFO - 2015-11-24 06:06:14,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 155160576 06:06:14 INFO - 2015-11-24 06:06:14,630 DEBUG : BROWSER_OUTPUT: 06:06:17 INFO - 2015-11-24 06:06:17,388 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56916/tests/kraken/driver.html?testName=json-parse-financial) 06:06:17 INFO - 2015-11-24 06:06:17,961 DEBUG : BROWSER_OUTPUT: RSS: Main: 154808320 06:06:17 INFO - 2015-11-24 06:06:17,962 DEBUG : BROWSER_OUTPUT: 06:06:19 INFO - 2015-11-24 06:06:19,530 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56916/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:20 INFO - 2015-11-24 06:06:20,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 167256064 06:06:20 INFO - 2015-11-24 06:06:20,093 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,893 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:22 INFO - 2015-11-24 06:06:22,494 DEBUG : BROWSER_OUTPUT: RSS: Main: 165494784 06:06:22 INFO - 2015-11-24 06:06:22,494 DEBUG : BROWSER_OUTPUT: 06:06:24 INFO - 2015-11-24 06:06:24,546 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:25 INFO - 2015-11-24 06:06:25,119 DEBUG : BROWSER_OUTPUT: RSS: Main: 172728320 06:06:25 INFO - 2015-11-24 06:06:25,120 DEBUG : BROWSER_OUTPUT: 06:06:27 INFO - 2015-11-24 06:06:27,519 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:28 INFO - 2015-11-24 06:06:28,108 DEBUG : BROWSER_OUTPUT: RSS: Main: 180674560 06:06:28 INFO - 2015-11-24 06:06:28,108 DEBUG : BROWSER_OUTPUT: 06:06:30 INFO - 2015-11-24 06:06:30,668 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:31 INFO - 2015-11-24 06:06:31,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 180662272 06:06:31 INFO - 2015-11-24 06:06:31,233 DEBUG : BROWSER_OUTPUT: 06:06:32 INFO - 2015-11-24 06:06:32,907 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56916/tests/kraken/driver.html?testName=ai-astar) 06:06:33 INFO - 2015-11-24 06:06:33,157 DEBUG : BROWSER_OUTPUT: RSS: Main: 238743552 06:06:33 INFO - 2015-11-24 06:06:33,157 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,158 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:06:33 INFO - 2015-11-24 06:06:33,158 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:06:33 INFO - 2015-11-24 06:06:33,158 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:06:33 INFO - 2015-11-24 06:06:33,158 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:06:33 INFO - 2015-11-24 06:06:33,158 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;105;109;109;110;98;108;108;101 06:06:33 INFO - 2015-11-24 06:06:33,158 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;127;150;159;131;161;149;123;160;123 06:06:33 INFO - 2015-11-24 06:06:33,159 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;161;161;163;161;160;161;160;161 06:06:33 INFO - 2015-11-24 06:06:33,159 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;87;92;71;73;72;106;88;72 06:06:33 INFO - 2015-11-24 06:06:33,159 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;149;92;94;101;141;92;94;149 06:06:33 INFO - 2015-11-24 06:06:33,159 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;113;110;109;111;110;109 06:06:33 INFO - 2015-11-24 06:06:33,159 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;146;145;145;146;146;146;146 06:06:33 INFO - 2015-11-24 06:06:33,159 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;97;96;96;95;96;97;96 06:06:33 INFO - 2015-11-24 06:06:33,160 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;55;58;62;57;57;58;57;59;56 06:06:33 INFO - 2015-11-24 06:06:33,160 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;70;71;70;70;70;71;71;70 06:06:33 INFO - 2015-11-24 06:06:33,160 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;83;85;85;84;84;84;85;86;85 06:06:33 INFO - 2015-11-24 06:06:33,160 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;116;122;123;118;123;125;123;119;123 06:06:33 INFO - 2015-11-24 06:06:33,160 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;147;155;162;150;139;148;149;152;144 06:06:33 INFO - 2015-11-24 06:06:33,160 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;66;67;66;67;67;69;68;67;66 06:06:33 INFO - 2015-11-24 06:06:33,161 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:06:33 INFO - 2015-11-24 06:06:33,161 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:06:33 INFO - 2015-11-24 06:06:33,161 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,938 06:06:33 INFO - 2015-11-24 06:06:33,161 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:06:33 INFO - 2015-11-24 06:06:33,161 DEBUG : BROWSER_OUTPUT: __startTimestamp1448373993156__endTimestamp 06:06:33 INFO - 2015-11-24 06:06:33,161 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.60 Median:109.00 stddev:4.06 (3.7%) stddev-sans-first:4.28 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 105.0 109.0 109.0 110.0 98.0 108.0 108.0 101.0 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.70 Median:152.00 stddev:15.88 (10.4%) stddev-sans-first:16.40 06:06:33 INFO - 2015-11-24 06:06:33,165 DEBUG : BROWSER_OUTPUT: Values: 154.0 127.0 150.0 159.0 131.0 161.0 149.0 123.0 160.0 123.0 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 161.0 161.0 163.0 161.0 160.0 161.0 160.0 161.0 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.30 Median:72.50 stddev:12.26 (16.9%) stddev-sans-first:12.53 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 87.0 92.0 71.0 73.0 72.0 106.0 88.0 72.0 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:109.90 Median:92.50 stddev:25.36 (27.4%) stddev-sans-first:26.24 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 149.0 92.0 94.0 101.0 141.0 92.0 94.0 149.0 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:1.20 (1.1%) stddev-sans-first:1.27 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 113.0 110.0 109.0 111.0 110.0 109.0 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,166 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 97.0 96.0 96.0 95.0 96.0 97.0 96.0 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.50 Median:57.50 stddev:1.96 (3.4%) stddev-sans-first:2.00 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: Values: 56.0 55.0 58.0 62.0 57.0 57.0 58.0 57.0 59.0 56.0 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 70.0 71.0 70.0 70.0 70.0 71.0 71.0 70.0 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.00 stddev:1.40 (1.6%) stddev-sans-first:0.88 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: Values: 81.0 83.0 85.0 85.0 84.0 84.0 84.0 85.0 86.0 85.0 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.20 Median:123.00 stddev:2.82 (2.3%) stddev-sans-first:2.96 06:06:33 INFO - 2015-11-24 06:06:33,167 DEBUG : BROWSER_OUTPUT: Values: 120.0 116.0 122.0 123.0 118.0 123.0 125.0 123.0 119.0 123.0 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.70 Median:149.50 stddev:6.73 (4.5%) stddev-sans-first:6.54 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: Values: 141.0 147.0 155.0 162.0 150.0 139.0 148.0 149.0 152.0 144.0 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: Values: 67.0 66.0 67.0 66.0 67.0 67.0 69.0 68.0 67.0 66.0 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:06:33 INFO - 2015-11-24 06:06:33,168 DEBUG : BROWSER_OUTPUT: 06:06:34 INFO - 2015-11-24 06:06:34,001 INFO : Browser exited with error code: 0 06:06:34 INFO - 2015-11-24 06:06:34,056 INFO : Completed test kraken (00:00:58) 06:06:34 INFO - 2015-11-24 06:06:34,056 INFO : Starting test v8_7 06:06:34 INFO - 2015-11-24 06:06:34,056 DEBUG : operating with platform_type : linux_ 06:06:34 INFO - 2015-11-24 06:06:34,056 INFO : Initialising browser for v8_7 test... 06:06:34 INFO - 2015-11-24 06:06:34,062 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKvOyGL/profile http://localhost:56916/getInfo.html 06:06:37 INFO - 2015-11-24 06:06:37,594 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:06:37 INFO - 2015-11-24 06:06:37,595 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:06:37 INFO - 2015-11-24 06:06:37,595 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:06:37 INFO - 2015-11-24 06:06:37,595 DEBUG : BROWSER_OUTPUT: __metrics 06:06:37 INFO - 2015-11-24 06:06:37,699 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] 06:06:38 INFO - 2015-11-24 06:06:38,833 INFO : Browser initialized. 06:06:38 INFO - 2015-11-24 06:06:38,834 INFO : Running cycle 1/1 for v8_7 test... 06:06:38 INFO - 2015-11-24 06:06:38,834 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKvOyGL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:06:50 INFO - 2015-11-24 06:06:50,507 DEBUG : BROWSER_OUTPUT: RSS: Main: 139309056 06:06:50 INFO - 2015-11-24 06:06:50,508 DEBUG : BROWSER_OUTPUT: 06:07:11 INFO - 2015-11-24 06:07:11,965 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56916/tests/v8_7/run.html (next: http://localhost:56916/tests/v8_7/run.html) 06:07:12 INFO - 2015-11-24 06:07:12,215 DEBUG : BROWSER_OUTPUT: RSS: Main: 629977088 06:07:12 INFO - 2015-11-24 06:07:12,215 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,216 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:07:12 INFO - 2015-11-24 06:07:12,216 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:07:12 INFO - 2015-11-24 06:07:12,216 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:07:12 INFO - 2015-11-24 06:07:12,217 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:07:12 INFO - 2015-11-24 06:07:12,217 DEBUG : BROWSER_OUTPUT: |0;Richards;165.89250165892503 06:07:12 INFO - 2015-11-24 06:07:12,217 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.9317738791423 06:07:12 INFO - 2015-11-24 06:07:12,217 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.7254735651682 06:07:12 INFO - 2015-11-24 06:07:12,217 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 06:07:12 INFO - 2015-11-24 06:07:12,217 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1108.5271317829458 06:07:12 INFO - 2015-11-24 06:07:12,217 DEBUG : BROWSER_OUTPUT: |5;Earley;793.6507936507936 06:07:12 INFO - 2015-11-24 06:07:12,218 DEBUG : BROWSER_OUTPUT: |6;Boyer;12195.121951219513 06:07:12 INFO - 2015-11-24 06:07:12,218 DEBUG : BROWSER_OUTPUT: |7;RegExp;25550 06:07:12 INFO - 2015-11-24 06:07:12,218 DEBUG : BROWSER_OUTPUT: |8;Splay;672.7629000653168 06:07:12 INFO - 2015-11-24 06:07:12,218 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 06:07:12 INFO - 2015-11-24 06:07:12,218 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:07:12 INFO - 2015-11-24 06:07:12,218 DEBUG : BROWSER_OUTPUT: __startTimestamp1448374032215__endTimestamp 06:07:12 INFO - 2015-11-24 06:07:12,218 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,219 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:07:12 INFO - 2015-11-24 06:07:12,219 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:07:12 INFO - 2015-11-24 06:07:12,219 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,219 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.89 Median:165.89 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,219 DEBUG : BROWSER_OUTPUT: Values: 165.9 06:07:12 INFO - 2015-11-24 06:07:12,220 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,220 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.93 Median:194.93 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,220 DEBUG : BROWSER_OUTPUT: Values: 194.9 06:07:12 INFO - 2015-11-24 06:07:12,220 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,220 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.73 Median:282.73 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,221 DEBUG : BROWSER_OUTPUT: Values: 282.7 06:07:12 INFO - 2015-11-24 06:07:12,221 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,222 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,222 DEBUG : BROWSER_OUTPUT: Values: 5191.7 06:07:12 INFO - 2015-11-24 06:07:12,222 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,222 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1108.53 Median:1108.53 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,222 DEBUG : BROWSER_OUTPUT: Values: 1108.5 06:07:12 INFO - 2015-11-24 06:07:12,223 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,223 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:793.65 Median:793.65 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,223 DEBUG : BROWSER_OUTPUT: Values: 793.7 06:07:12 INFO - 2015-11-24 06:07:12,223 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,223 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12195.12 Median:12195.12 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,223 DEBUG : BROWSER_OUTPUT: Values: 12195.1 06:07:12 INFO - 2015-11-24 06:07:12,223 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25550.00 Median:25550.00 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: Values: 25550.0 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:672.76 Median:672.76 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: Values: 672.8 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 06:07:12 INFO - 2015-11-24 06:07:12,224 DEBUG : BROWSER_OUTPUT: Values: 4835.7 06:07:12 INFO - 2015-11-24 06:07:12,225 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:07:12 INFO - 2015-11-24 06:07:12,225 DEBUG : BROWSER_OUTPUT: 06:07:13 INFO - 2015-11-24 06:07:13,013 INFO : Browser exited with error code: 0 06:07:13 INFO - 2015-11-24 06:07:13,030 INFO : Completed test v8_7 (00:00:38) 06:07:13 INFO - 2015-11-24 06:07:13,166 INFO : Completed test suite (00:20:09) 06:07:13 INFO - 2015-11-24 06:07:13,166 DEBUG : Working with test: dromaeo_css 06:07:13 INFO - 2015-11-24 06:07:13,166 DEBUG : Generating results file: dromaeo_css 06:07:13 INFO - 2015-11-24 06:07:13,167 DEBUG : Working with test: kraken 06:07:13 INFO - 2015-11-24 06:07:13,167 DEBUG : Generating results file: kraken 06:07:13 INFO - 2015-11-24 06:07:13,168 INFO : javascript benchmark 06:07:13 INFO - 2015-11-24 06:07:13,168 DEBUG : Working with test: v8_7 06:07:13 INFO - 2015-11-24 06:07:13,168 DEBUG : Generating results file: v8_7 06:07:13 INFO - 2015-11-24 06:07:13,168 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:13 INFO - 2015-11-24 06:07:13,218 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 06:07:13 INFO - 2015-11-24 06:07:13,218 DEBUG : process_Request line: dromaeo_css 6994.63 graph.html#tests=[[72,63,35]] 06:07:13 INFO - 2015-11-24 06:07:13,218 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:13 INFO - 2015-11-24 06:07:13,322 DEBUG : process_Request line: kraken 1502.00 graph.html#tests=[[232,63,35]] 06:07:13 INFO - 2015-11-24 06:07:13,322 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:13 INFO - 2015-11-24 06:07:13,357 DEBUG : process_Request line: v8_7 20739.37 graph.html#tests=[[230,63,35]] 06:07:13 INFO - 2015-11-24 06:07:13,362 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3171.682831716829, 3221.629512606048, 3263.03740396583, 3268.9346213075723, 3290.7202887754534, 5257.763400646971, 5370.758315875785, 5453.127499600065, 5471.1519714444275, 5476.342838859333, 5507.421720719327, 5529.806456774014, 5559.9444005559935, 5577.051901176799, 5636.18275350074, 4750.667453278272, 5050.924236136461, 5056.342675452195, 5085.389753229618, 5108.412532558747, 5110.744462776865, 5117.974410127944, 5164.715940623283, 5188.662736922107, 5193.714345710984, 4858.5627293543575, 4878.438979517366, 4949.50504949504, 4965.3048573199785, 4969.577585905203, 5003.799848006075, 5014.24786282057, 5017.0, 5035.723035233061, 5050.116229659795, 4944.653874228806, 4989.251612258154, 5001.099802035632, 5021.447640759514, 5057.620678449094, 5182.378114626231, 5195.922061169085, 5257.947420525784, 5288.8413347599635, 5299.125644268691, 5992.2809262888295, 5995.040793473067, 5996.16053752475, 6009.879802403927, 6015.6691381974415, 5793.188953546507, 5996.430339117776, 6019.187409699722, 6022.578419510636, 6023.096535519663, 5458.2904222450825, 5505.311836020497, 5522.171674248854, 5530.557555395559, 5576.1356989666265, 6010.0, 6018.0, 6035.973884439656, 6072.453479186895, 6074.54440916933, 5576.275084239023, 5814.360420353758, 5819.098039803837, 5821.27234095738, 5882.176495290662, 5823.767049317979, 5862.882742345129, 5871.148683440877, 5951.880962380728, 5955.583109182316, 5948.137520059568, 5982.49148822355, 5986.012307969223, 5992.161097446361, 6023.337432882379, 5984.102384642339, 5984.970075149597, 5989.8203053908455, 5990.011648078105, 5992.460678538865, 4416.050549131952, 4444.355568442557, 4508.504064552896, 4594.1271158479785, 4610.05493873755, 4051.47330846988, 4061.654759345489, 4066.9796651016554, 4068.0846809468226, 4099.159672267178, 5546.584006199551, 5582.162675598693, 5592.5805564582815, 5645.435456454322, 5654.236678048493, 5786.739596718157, 5882.764689412375, 5893.911591326134, 5900.468957793819, 6041.697915104315, 5480.123180291134, 5638.407967163471, 5644.491995720323, 5649.604527683023, 5657.4908258256955, 5178.352705911761, 5182.352205974254, 5199.636025478256, 5203.323567936144, 5246.108161612535, 5925.911111333334, 6043.395660434009, 6047.667378294236, 6088.665123418255, 6175.228096487939, 6009.36901625323, 6036.486898613492, 6043.667598282137, 6045.304789949212, 6052.788152414644, 6190.226221722285, 6191.721372538336, 6192.287886892885, 6200.472959798468, 6204.3795620439405, 5699.2590963174525, 5747.453991870766, 5771.567132465166, 5824.301083869963, 5833.737481813413, 5885.588008839341, 5913.526917846476, 5914.822555323347, 5923.555733319932, 5958.493528049991, 5793.15999180125, 5794.7102644866745, 5794.884102318014, 5795.130730390475, 5800.912986305125, 5639.295088113986, 5764.798232061858, 5766.163906233656, 5803.12953057028, 5936.3470018297085, 5709.22925405065, 5709.34342550612, 5711.171880077282, 5714.0, 5716.628419152742, 5773.191753154478, 5803.448672376117, 5820.330661973758, 5820.388859169726, 5880.088586269135, 5490.258815060076, 5586.608937374507, 5587.966226248157, 5593.1889875966945, 5706.686132262599], "unit": "ms", "name": "dojo.html", "value": 5453.534605501597}, {"lowerIsBetter": false, "replicates": [553.219959856602, 598.1565991951348, 598.2282855116899, 604.6855635069763, 605.0772571827963, 31518.369632607362, 32174.0, 32190.195245118826, 32240.355192896128, 32243.35513289736, 33224.33551328978, 42286.36570451446, 43013.569864301346, 43041.784791076076, 43076.0, 8669.306455483578, 8787.78030549235, 8789.560521973908, 8794.956025219864, 8799.120087991181, 7639.6944122235045, 7796.532208067534, 8637.568121593926, 8757.912420875806, 8758.211760941515, 7572.204918483562, 10503.579856805718, 10793.244472886901, 10803.135749140089, 10816.026557609814, 10328.948355258213, 10426.16590672752, 10435.947820260888, 10437.217208709377, 10444.11225045872, 7772.7668169954995, 7941.404394670365, 10175.542100605491, 10293.786835830333, 10307.84538231927, 44869.32696009563, 46041.53958460406, 46092.76953615244, 46166.0, 46167.0, 3304.4217261979297, 3362.9159271018175, 3513.508108864761, 3678.1907980244305, 3772.4152756322815, 29382.0, 29616.8519157405, 29786.255343616365, 30044.54933176004, 30340.69659303401, 3742.3450896093077, 3758.1356288053626, 3822.770633761955, 3844.1350696093436, 3959.98020009901, 256808.0, 257950.0, 258361.9999999981, 258738.0, 259089.0, 57621.0, 57958.0, 58214.708926455096, 58233.0, 58234.12648810271, 62431.37568624256, 62461.0, 62567.6871615639, 62649.686751566864, 62772.0, 15359.846401536066, 15602.687946241238, 15742.291596878167, 15749.527514174593, 15760.60598485047, 10524.158067354594, 10531.368117912949, 10548.947255263827, 10555.208359373079, 10581.788364232672, 19142.7557208781, 20130.798692013184, 20240.392788216377, 20301.187952481734, 20321.89839050795, 7943.64253608589, 7995.600219988977, 8094.271515563628, 8106.918930810616, 8158.102608713037, 972.5526257921435, 1015.3197357770312, 1050.847627094067, 1058.7935352606169, 1086.9130469562417, 25084.74915250824, 25537.48925021489, 25731.871340643553, 26472.735272647027, 27894.44211115807, 1003.2174903575223, 1026.6919924022764, 1069.1820757120809, 1104.3539529375246, 1108.141190577293, 14331.211783352015, 14485.130892146504, 14574.927125364306, 14696.706065878836, 14723.337449814784, 308943.0, 309313.0, 309759.0240975929, 324205.0, 331866.0, 50120.49879501231, 52030.73984630129, 52201.2169817453, 52262.0, 52402.47597523976, 1336.4052996416501, 1352.0805852020524, 1360.333436616051, 1360.8026836108907, 1364.0724307471014, 24412.75587244105, 24593.50812983766, 28392.574111387934, 28598.428031438838, 28845.711542884303, 43905.78047109872, 44410.555894440644, 44501.554984449744, 44591.0, 44595.55404445914, 225819.0, 225831.0, 227364.0, 228240.0, 228273.0, 31861.36277274488, 31874.84062579672, 32784.67215327816, 32941.34117317688, 32963.175920601214, 40649.1870162601, 41060.79469602633, 42689.0, 42727.3590896143, 42900.57099428966, 33478.83260583682, 33857.0, 33902.32195356128, 33925.83037084799, 34742.0, 35378.292434151685, 36886.44670330001, 37672.246555069294, 37848.0, 38036.42945355766, 35657.46513802347, 36574.817125914204, 36577.268454630226, 36619.63380366162, 36775.44836827396, 30434.54348184823, 33082.83458582788, 33546.161345966546, 33553.49669754907, 34023.31953360968, 24692.0, 25168.548250970463, 25379.238622841345, 25545.74454255508, 25931.092411765498], "unit": "ms", "name": "ext.html", "value": 18399.199235245014}, {"lowerIsBetter": false, "replicates": [353.2951761236334, 356.0138416586058, 357.11079412263473, 360.41972424396715, 362.0983750461351, 14425.42298308069, 14427.134371937665, 16598.585035374123, 17743.0, 19006.809931900694, 10704.571817127326, 10983.670489885297, 16036.518904432856, 16524.339026438927, 16525.504234872944, 4720.881977950553, 4846.200376937801, 4848.224284114543, 4848.87877803056, 4849.51504849514, 3681.9631803682023, 3768.2275133597545, 4955.653104282702, 4985.476524964881, 5227.21591761237, 5314.813981510648, 5333.253344531769, 5349.5185433311, 5352.116900711398, 5357.223202635615, 5061.949380506203, 5218.582513398919, 5223.1642937130255, 5326.307580014612, 5328.946710532903, 4952.281919121707, 4981.078500477423, 5040.319556859814, 5044.369453818273, 5048.747562621854, 11934.820977685342, 12149.574764883188, 12155.635330940086, 12160.635180944497, 16587.83412165875, 2011.5071807407132, 2079.750429948401, 2093.905774240163, 2117.7776333484917, 2123.50097727034, 9553.522323883779, 10075.848862267072, 10483.161347092215, 10546.578136874516, 13361.933190334083, 1862.8602854785945, 1879.5583037986062, 1889.0, 1904.1050706168078, 1937.3703546347494, 60105.6994715028, 63067.6846615764, 63129.684351578406, 63528.0, 63552.0, 10132.645357412455, 10244.846327305097, 10312.381257124596, 10316.226283028804, 10405.47972601367, 13545.932270338782, 13590.32048397596, 13628.114172578751, 13632.522861699794, 13650.453981840814, 7631.503952243086, 7681.0, 7697.23027697237, 7721.65252563636, 7748.263914928073, 7511.549307041595, 7512.887306690404, 7542.0, 7582.431317651088, 7588.317051465395, 4342.522322544518, 4378.912421751547, 4390.165868484977, 4461.24158892989, 4511.390962220058, 1578.4948816378649, 1651.9421820236475, 1698.0915210362398, 1795.6678014567347, 1832.1755210155484, 924.8612708093705, 940.5438362394233, 959.8608201810838, 974.1086905481546, 995.2237254941158, 14643.92678036603, 15899.28453219608, 16910.239039243264, 17348.30606775715, 17685.646287074185, 842.5492361586645, 893.7363477774072, 957.4351132831523, 962.3407965543626, 999.4253304350028, 9385.779848619635, 9610.90389096114, 9659.420434773936, 9675.564599592895, 9744.853827192597, 557418.0, 558552.0, 560497.0, 587743.0, 588925.0, 78959.21040789633, 81337.18662813296, 81691.18308816836, 82172.58913705393, 82347.58826205951, 1054.478033373485, 1055.5355643516982, 1073.9677809665723, 1083.631565267797, 1083.7128161037174, 15080.396784128512, 15353.923230383776, 15399.0, 15402.537923862303, 15407.460738874086, 34883.47674784931, 35120.29759404849, 35334.46998294976, 35690.46464302985, 35990.28019439544, 45343.31985020293, 46036.07927841358, 46702.0, 47097.0, 47149.76425117852, 9024.187823095954, 9440.527973601429, 9495.572699228413, 9496.525173741424, 9498.95250523743, 10836.945815270874, 13974.301284935917, 16845.578860528094, 17228.310867565157, 17308.480745577654, 13853.099548529313, 14027.088239264414, 14102.36539355752, 14550.4907328243, 14667.853321467075, 13963.30183490801, 14116.929415352857, 14174.929125354307, 14361.281935902955, 14520.782188267396, 11777.470013849226, 14253.0, 14524.273786310854, 14929.402823886923, 15038.62403439879, 9680.75798105053, 9702.369345992487, 9806.754831128994, 9938.055884690943, 9938.403695778277, 13792.172469651852, 14652.706945861237, 14731.558053258419, 15030.098194108388, 15279.694406112038], "unit": "ms", "name": "jquery.html", "value": 8663.791083644639}, {"lowerIsBetter": false, "replicates": [1582.69137518184, 1671.8746094042947, 1747.5001870184278, 1756.350150444336, 1799.2173404569005, 6599.142111525501, 6758.932410675891, 7543.471956963015, 7660.540367577949, 7680.155182929873, 7705.49914255573, 7716.729914452995, 7780.183080776522, 7798.2201779822135, 7800.258975397328, 1910.646530391875, 1952.570434504414, 2199.604071267171, 2212.9446763830874, 2216.5788500184995, 2334.2997100869757, 2361.988190059047, 2363.3382652857226, 2369.656399822025, 2371.1937941100023, 2285.4172186092524, 2286.9937227619794, 2340.6489026646054, 2346.4368551547677, 2348.988255058722, 1981.9900900495477, 2008.1365013044385, 2239.496113374494, 2295.070496448941, 2318.0, 2290.81673466124, 2298.9195378161853, 2306.1351993002622, 2306.919257825968, 2308.4690521180223, 7672.271134242238, 7785.688572457095, 7790.220977902221, 7792.142864284924, 7802.33680137189, 4732.266498692674, 4793.904121917542, 4796.5683088521855, 4799.0401919616215, 4836.782344794493, 3139.8587063582195, 3144.5440411140257, 3182.6339970903177, 3217.3565286942703, 3266.7549933754826, 989.8267803134495, 1001.0940099210227, 1004.091297375876, 1024.482636268687, 1044.5247412427327, 10636.0, 10636.574537018587, 10673.252872298943, 10693.251472396936, 10694.144468442506, 4252.107057517956, 4368.6068253856665, 4370.431843860341, 4433.645308375322, 4510.571495707903, 9043.502607356528, 9133.543322833964, 9152.8627070594, 9153.77115572116, 9156.175944165057, 4726.598239149711, 4748.525147485224, 4765.833195838129, 4767.332573439721, 4778.737169455644, 4308.224519586442, 4310.762908040025, 4315.201687687725, 4317.266064768997, 4320.503142138631, 3730.477733117366, 3751.2309976454762, 3753.8123093845743, 3775.4714339992424, 3793.677537409296, 1243.0801207106676, 1245.862955074941, 1249.6688377579949, 1252.874712528758, 1259.376608578741, 1343.6372179511477, 1344.8184495093037, 1359.57853065548, 1377.3457607636492, 1380.1718968618748, 6440.198840418072, 6677.465802735866, 7414.369778568882, 7578.052743407075, 7600.619969001638, 607.7143742441045, 616.488314698799, 622.1507642068544, 637.4708991537013, 641.5573254454505, 5782.190493330937, 5784.566157538202, 5801.6809075500405, 5807.245058142415, 5839.0949402842625, 9275.67535136267, 9381.718548443558, 9392.34253602255, 9564.808703825884, 9568.378055426376, 9124.087591240956, 9282.535873206312, 9362.531873406302, 9422.434653920787, 9435.528223588793, 7157.713691452388, 7201.603911784798, 7230.2046774854725, 7283.927160728324, 7297.0, 6873.656317184021, 7067.893981590383, 7085.220625731268, 7090.113735783028, 7095.432365410651, 7992.6802927884555, 8133.877991830245, 8147.307478864363, 8279.047909490486, 8299.377546684143, 8125.431219814793, 8291.95854020726, 8323.12607176262, 8460.746177614681, 8469.534175620154, 6789.93210067893, 6855.383015528626, 6861.725530978705, 6867.759628412981, 6869.828254293483, 7516.849663006818, 7571.674956882413, 7577.886331705139, 7594.088709354913, 7610.1628820828555, 7038.3665470107935, 7042.7887163385185, 7155.427565794828, 7194.388476979306, 7196.208417074013, 6995.580265184105, 7025.68384422692, 7033.261507541839, 7120.501564890408, 7141.392981596622, 6083.452489275986, 6208.937910620836, 7134.286571342825, 7170.749023784142, 7182.245864184186, 6408.743650254124, 6488.286288508355, 6529.575577587441, 6537.771178008747, 6552.803415897531, 6508.056331831953, 6546.443552298108, 6576.790853853633, 6583.506237032146, 6625.701843416961], "unit": "ms", "name": "mootools.html", "value": 4452.032522593701}, {"lowerIsBetter": false, "replicates": [337.1587888218893, 338.5362053986039, 340.88921100642295, 343.7250199840127, 345.89968909016386, 5822.854428639275, 5868.50117739992, 5872.119182122684, 5913.64518128912, 5994.340622531518, 5794.159846822208, 5886.264216972879, 5899.0, 5910.940890591093, 5914.970425147879, 4594.104149690812, 4609.585137337639, 4609.815607375701, 4610.377599024139, 4610.792514336855, 4410.228210063242, 4481.282994720846, 4509.616682581985, 4544.545545445446, 4600.5629465200755, 4498.707584007029, 4505.076459325846, 4581.129585378767, 4581.152486789954, 4583.083383323332, 4559.0, 4560.950981274309, 4561.133384656904, 4581.671315318554, 4582.908341833178, 4006.1186538961383, 4035.1929614077007, 4042.312806822818, 4043.4541336919433, 4050.878473645766, 5476.945230547723, 5607.579431542651, 5617.522510586605, 5667.3482549506925, 5688.203651488795, 5263.210518422229, 5263.710495922723, 5279.41926388097, 5279.736013199325, 5288.127458969266, 5988.981873081587, 6308.3060863305, 6311.6213027218055, 6318.557700960934, 6323.525735569805, 4272.636825869805, 4297.39836422901, 4384.254676704968, 4386.245501799274, 4390.8902277443, 6468.74125034998, 6571.2771595124495, 6573.967130164318, 6574.835629109262, 6581.407673309377, 4415.072834704683, 4419.292913133948, 4422.425084739028, 4422.624076953495, 4431.423914891044, 4394.494633117232, 4480.484744254387, 4482.170798402306, 4509.549045095464, 4565.908681826345, 5258.158694608921, 5258.50044245796, 5259.15853463444, 5263.052650522943, 5264.42091369949, 6347.079673447324, 6360.936390636127, 6361.300256971729, 6362.427381535684, 6368.235811702532, 1595.9601009974845, 1614.7254966655696, 1615.3780794394008, 1615.6122530592572, 1615.9272832722556, 1063.8936106389299, 1064.3986147826608, 1072.7961687279392, 1074.5003573338279, 1096.681962230944, 6094.481969032593, 6161.815145545641, 6173.197484327098, 6184.8763024739255, 6190.876182476325, 6566.967165164239, 6593.439557637558, 6593.934060659332, 6594.07682924391, 6595.637239951753, 6019.428154325332, 6116.938830611637, 6172.259328880563, 6183.628982261079, 6186.505079593621, 3539.5752509699, 3556.7688100273917, 3557.3774589446743, 3561.3233485637647, 3563.625819288989, 6784.762533311409, 6785.084013658192, 6788.626625535643, 6792.524523283323, 6795.2185498667295, 6699.598024118569, 6704.631245281556, 6708.429783468362, 6718.596884186964, 6719.193696756421, 6639.43564796988, 6723.831904202336, 6746.628935408599, 6915.582305627334, 6919.37725604698, 6366.745330186926, 6526.477881769542, 6635.071090047494, 6640.667966601747, 6652.301508341748, 6456.741730330734, 6562.0, 6587.4071333580205, 6596.670166491752, 6599.60402375859, 6887.104676392238, 6891.138607674041, 6892.862142757217, 6899.310068993061, 6914.274001229798, 4405.118976204836, 4462.576055274743, 4505.301678239747, 4507.346434766875, 4517.661175411923, 6579.802605921638, 6596.7031483584315, 6606.372394622503, 6612.702428390638, 6627.966860165861, 6437.195350581177, 6442.581232219891, 6442.774502892376, 6452.9677351612945, 6455.935440645533, 6242.687865606611, 6380.202474690664, 6384.521160912857, 6386.744530218925, 6405.743770249138, 6266.122742816101, 6271.090691849748, 6273.686315684289, 6275.3724627536885, 6282.937170628418, 6418.4865210783955, 6421.903671444838, 6424.100625912282, 6428.967855160881, 6443.0, 6105.0, 6292.528060395397, 6294.181756371642, 6299.6535190564955, 6302.086197501245], "unit": "ms", "name": "prototype.html", "value": 4790.60499878303}, {"lowerIsBetter": false, "replicates": [719.74089327842, 728.9416846652268, 753.9623018849056, 770.6146926536732, 844.8183640517287, 13699.246541440229, 13892.51376201832, 13897.513587024456, 13916.373763180656, 13925.09486883352, 13260.46958121674, 13378.13042152262, 13638.181709097462, 13707.588772336821, 13719.725605487909, 805.2551389964289, 828.6561077153004, 829.5852073963018, 831.2103501673404, 831.3515457942784, 814.975550733476, 821.7411515372638, 827.7930517370658, 828.3994104274383, 829.4401279136558, 850.0224741547239, 852.6461518469828, 860.7718954477068, 865.5931712095337, 865.68835219321, 854.487307615432, 860.27736701171, 863.1713554987189, 865.1737590600961, 865.3682811547606, 626.6177631644694, 629.3894921925724, 631.6368088349171, 633.6578247746212, 634.191405957408, 12749.681257968532, 12780.297083660394, 12940.35298235094, 12943.093983421164, 12952.676183095404, 10805.243632945698, 11125.443727813657, 11139.554417823278, 11298.830517542243, 11523.48144333507, 9831.213502919749, 9847.950760246153, 9905.0, 10025.147862431702, 10541.0, 3612.837422316002, 3689.612590677994, 3702.5186725725484, 3702.5371828521434, 3785.0915780212827, 40418.39372409416, 41234.58765412367, 41826.581734182575, 45429.54570454286, 50675.0, 9844.950775246078, 9900.950495247478, 9907.3560218587, 9953.452560109263, 10148.492575371201, 17563.38528151534, 17642.647147057243, 17642.823571764377, 17654.382096626556, 17663.64672706539, 7419.07261592301, 7438.702451901863, 7439.962800186073, 7629.237076292437, 7834.0, 4327.459067616548, 4338.978305108454, 4388.363687265329, 4390.758508282075, 4515.954840451619, 3049.268175637831, 3102.612173478315, 3104.425681248976, 3106.6116735408073, 3107.829069401205, 562.5499600319728, 566.0, 566.4052744618134, 567.6650776042072, 581.4505292498633, 621.586644881149, 648.9091527186198, 652.7584793626388, 667.6361383046251, 693.9236683964759, 8789.882006635542, 8805.515696636618, 8828.293736501064, 8913.242374398249, 8967.910320896708, 631.9936800632026, 640.8782331357028, 658.8385845467891, 672.3377473188905, 679.864027194563, 3826.7129965252716, 4370.584794444522, 4408.801603927831, 4428.446444194476, 4520.344550040226, 51932.48067519352, 53298.20052699213, 53407.19889201665, 53497.0, 53534.73232633812, 46090.308645369674, 48463.5153648459, 48598.0, 51216.743916280924, 53451.732741336826, 42767.144657106684, 46994.0, 50512.242316365286, 50533.0, 50804.745976269885, 8680.521030128168, 8854.0, 9189.310801689766, 9223.0, 9258.166764990914, 36296.0, 36991.81504092462, 37136.25727485489, 37359.25281494409, 37531.43702844405, 41967.370489443296, 42113.0, 42617.78691106525, 42714.5728542723, 47958.76020619875, 10597.629082982367, 10600.455890586358, 10839.458027098772, 10900.672979810619, 10927.0, 11981.700457488632, 12158.45286962071, 12217.266963982189, 12252.938735306267, 12643.810342844681, 11357.886421136014, 11610.593629222936, 11686.94156529212, 11983.221090629082, 12021.218620789621, 11227.326360418401, 11350.432478376213, 11359.54561817551, 11492.0, 11800.645980620595, 11481.712957175803, 11499.0, 11537.48081336325, 11938.820917686067, 11950.940245298718, 9552.0, 9693.127618514085, 9800.166985806287, 10116.393016419039, 10228.590856365661, 9158.58786354629, 9249.491277979776, 9277.350585459222, 9633.150687656036, 9992.65025724096], "unit": "ms", "name": "yui.html", "value": 6315.653170261078}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 105.0, 109.0, 109.0, 110.0, 98.0, 108.0, 108.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 106.6}, {"lowerIsBetter": true, "replicates": [154.0, 127.0, 150.0, 159.0, 131.0, 161.0, 149.0, 123.0, 160.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 161.0, 161.0, 163.0, 161.0, 160.0, 161.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 87.0, 92.0, 71.0, 73.0, 72.0, 106.0, 88.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.3}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 149.0, 92.0, 94.0, 101.0, 141.0, 92.0, 94.0, 149.0], "unit": "ms", "name": "audio-oscillator", "value": 109.9}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 113.0, 110.0, 109.0, 111.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.0}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 58.0, 62.0, 57.0, 57.0, 58.0, 57.0, 59.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.5}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 70.0, 71.0, 70.0, 70.0, 70.0, 71.0, 71.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.3}, {"lowerIsBetter": true, "replicates": [81.0, 83.0, 85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 86.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.2}, {"lowerIsBetter": true, "replicates": [120.0, 116.0, 122.0, 123.0, 118.0, 123.0, 125.0, 123.0, 119.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.2}, {"lowerIsBetter": true, "replicates": [141.0, 147.0, 155.0, 162.0, 150.0, 139.0, 148.0, 149.0, 152.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.7}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 67.0, 66.0, 67.0, 67.0, 69.0, 68.0, 67.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.0}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.89250165892503], "unit": "score", "name": "Richards", "value": 212.80045599999988}, {"lowerIsBetter": false, "replicates": [194.9317738791423], "unit": "score", "name": "DeltaBlue", "value": 339.18533999999994}, {"lowerIsBetter": false, "replicates": [282.7254735651682], "unit": "score", "name": "Encrypt", "value": 941.4821969999996}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [1108.5271317829458], "unit": "score", "name": "RayTrace", "value": 667.5425244755243}, {"lowerIsBetter": false, "replicates": [793.6507936507936], "unit": "score", "name": "Earley", "value": 839.7433799999998}, {"lowerIsBetter": false, "replicates": [12195.121951219513], "unit": "score", "name": "Boyer", "value": 54.64996600000001}, {"lowerIsBetter": false, "replicates": [25550.0], "unit": "score", "name": "RegExp", "value": 35.65499021526421}, {"lowerIsBetter": false, "replicates": [672.7629000653168], "unit": "score", "name": "Splay", "value": 121.12885533980585}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7"}]} 06:07:13 INFO - RETURN: dromaeo_css: 6994.63 06:07:13 INFO - RETURN: kraken: 1502.00 06:07:13 INFO - RETURN: v8_7: 20739.37 06:07:13 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "20739.37"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1502.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6994.63"}}} 06:07:13 INFO - Return code: 0 06:07:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:07:13 INFO - # TBPL SUCCESS # 06:07:13 INFO - Running post-action listener: _resource_record_post_action 06:07:13 INFO - Running post-run listener: _resource_record_post_run 06:07:14 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 4096; Write bytes: 500211712; Read time: 32; Write time: 313616 06:07:14 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 228782080; Read time: 0; Write time: 198144 06:07:14 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 4096; Write bytes: 264531968; Read time: 32; Write time: 114560 06:07:14 INFO - Running post-run listener: _upload_blobber_files 06:07:14 INFO - Blob upload gear active. 06:07:14 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:07:14 INFO - Copying logs to upload dir... 06:07:14 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1266.005061 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-24 06:07:12.201445) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:12.204548) ========= 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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.008770 build_url: 'https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:12.257320) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:12.257716) ========= 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=5804b9f07a53938c557deee7000001c5-1448370335.744871-1572465837 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:12.308366) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 06:07:12.308712) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 06:07:12.309037) ========= ========= Total master_lag: 0.24 =========