builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-028 starttime: 1448372764.19 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.192306) ========= 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.192969) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.193837) ========= 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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006671 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.218224) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.218589) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.275009) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.275328) ========= 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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017113 ========= master_lag: 0.18 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.474581) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.474934) ========= 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.478128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.478483) ========= 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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 05:46:05-- 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.4M=0s 2015-11-24 05:46:05 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.176826 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.685516) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.685830) ========= 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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.077699 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.777932) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:04.778270) ========= 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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 05:46:05,381 truncating revision to first 12 chars 2015-11-24 05:46:05,381 Setting DEBUG logging. 2015-11-24 05:46:05,381 attempt 1/10 2015-11-24 05:46:05,381 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:05,609 unpacking tar archive at: mozilla-inbound-cf8cf1a039dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.493391 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:05.291794) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:05.292336) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:05.304222) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:46:05.304567) ========= 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:05.304996) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-11-24 05:46:05.305306) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:46:05 INFO - MultiFileLogger online at 20151124 05:46:05 in /builds/slave/test-pgo 05:46:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:05 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:46:05 INFO - {'append_to_log': False, 05:46:05 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:46:05 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:46:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:46:05 INFO - 'branch': 'Mozilla-Inbound', 05:46:05 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:05 INFO - 'config_files': ('talos/linux_config.py',), 05:46:05 INFO - 'default_actions': ('clobber', 05:46:05 INFO - 'read-buildbot-config', 05:46:05 INFO - 'download-and-extract', 05:46:05 INFO - 'populate-webroot', 05:46:05 INFO - 'create-virtualenv', 05:46:05 INFO - 'install', 05:46:05 INFO - 'run-tests'), 05:46:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:05 INFO - 'download_minidump_stackwalk': True, 05:46:05 INFO - 'download_symbols': 'ondemand', 05:46:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:05 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:05 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:05 INFO - 'installer_path': 'installer.exe', 05:46:05 INFO - 'log_level': 'info', 05:46:05 INFO - 'log_name': 'talos', 05:46:05 INFO - 'log_to_console': True, 05:46:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:46:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:46:05 INFO - 'opt_config_files': (), 05:46:05 INFO - 'pip_index': False, 05:46:05 INFO - 'sps_profile': False, 05:46:05 INFO - 'sps_profile_interval': 0, 05:46:05 INFO - 'suite': 'dromaeojs-e10s', 05:46:05 INFO - 'system_bits': '64', 05:46:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:05 INFO - 'title': 'talos-linux64-ix-028', 05:46:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:05 INFO - 'use_talos_json': True, 05:46:05 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:46:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:06 INFO - 'work_dir': 'build'} 05:46:06 INFO - ##### 05:46:06 INFO - ##### Running clobber step. 05:46:06 INFO - ##### 05:46:06 INFO - Running pre-action listener: _resource_record_pre_action 05:46:06 INFO - Running main action method: clobber 05:46:06 INFO - rmtree: /builds/slave/test-pgo/build 05:46:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:46:07 INFO - Running post-action listener: _resource_record_post_action 05:46:07 INFO - ##### 05:46:07 INFO - ##### Running read-buildbot-config step. 05:46:07 INFO - ##### 05:46:07 INFO - Running pre-action listener: _resource_record_pre_action 05:46:07 INFO - Running main action method: read_buildbot_config 05:46:07 INFO - Using buildbot properties: 05:46:07 INFO - { 05:46:07 INFO - "properties": { 05:46:07 INFO - "buildnumber": 206, 05:46:07 INFO - "product": "firefox", 05:46:07 INFO - "script_repo_revision": "production", 05:46:07 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 05:46:07 INFO - "repository": "", 05:46:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 05:46:07 INFO - "buildid": "20151124043011", 05:46:07 INFO - "slavename": "talos-linux64-ix-028", 05:46:07 INFO - "pgo_build": "True", 05:46:07 INFO - "basedir": "/builds/slave/test-pgo", 05:46:07 INFO - "project": "", 05:46:07 INFO - "platform": "ubuntu64_hw", 05:46:07 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:46:07 INFO - "slavebuilddir": "test", 05:46:07 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 05:46:07 INFO - "branch": "mozilla-inbound", 05:46:07 INFO - "repo_path": "integration/mozilla-inbound", 05:46:07 INFO - "stage_platform": "linux64-pgo", 05:46:07 INFO - "builduid": "2efe7578203948ee9e0ca2d583784c63", 05:46:07 INFO - "revision": "cf8cf1a039dd370d157b2afaa1fed719f1e34069" 05:46:07 INFO - }, 05:46:07 INFO - "sourcestamp": { 05:46:07 INFO - "repository": "", 05:46:07 INFO - "hasPatch": false, 05:46:07 INFO - "project": "", 05:46:07 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:46:07 INFO - "changes": [ 05:46:07 INFO - { 05:46:07 INFO - "category": null, 05:46:07 INFO - "files": [ 05:46:07 INFO - { 05:46:07 INFO - "url": null, 05:46:07 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:07 INFO - } 05:46:07 INFO - ], 05:46:07 INFO - "repository": "", 05:46:07 INFO - "rev": "cf8cf1a039dd370d157b2afaa1fed719f1e34069", 05:46:07 INFO - "who": "sendchange", 05:46:07 INFO - "when": 1448372711, 05:46:07 INFO - "number": 6700159, 05:46:07 INFO - "comments": "", 05:46:07 INFO - "project": "", 05:46:07 INFO - "at": "Tue 24 Nov 2015 05:45:11", 05:46:07 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:46:07 INFO - "revlink": "", 05:46:07 INFO - "properties": [ 05:46:07 INFO - [ 05:46:07 INFO - "buildid", 05:46:07 INFO - "20151124043011", 05:46:07 INFO - "Change" 05:46:07 INFO - ], 05:46:07 INFO - [ 05:46:07 INFO - "builduid", 05:46:07 INFO - "2efe7578203948ee9e0ca2d583784c63", 05:46:07 INFO - "Change" 05:46:07 INFO - ], 05:46:07 INFO - [ 05:46:07 INFO - "pgo_build", 05:46:07 INFO - "True", 05:46:07 INFO - "Change" 05:46:07 INFO - ] 05:46:07 INFO - ], 05:46:07 INFO - "revision": "cf8cf1a039dd370d157b2afaa1fed719f1e34069" 05:46:07 INFO - } 05:46:07 INFO - ], 05:46:07 INFO - "revision": "cf8cf1a039dd370d157b2afaa1fed719f1e34069" 05:46:07 INFO - } 05:46:07 INFO - } 05:46:07 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:07 INFO - Running post-action listener: _resource_record_post_action 05:46:07 INFO - ##### 05:46:07 INFO - ##### Running download-and-extract step. 05:46:07 INFO - ##### 05:46:07 INFO - Running pre-action listener: _resource_record_pre_action 05:46:07 INFO - Running main action method: download_and_extract 05:46:07 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:46:07 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:07 INFO - https://queue.taskcluster.net/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:46:07 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:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/yMeUVzObQTiF-4glnAyc3Q/artifacts/public/build/test_packages.json 05:46:07 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:07 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:07 INFO - Downloaded 1302 bytes. 05:46:07 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:46:07 INFO - Using the following test package requirements: 05:46:07 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:07 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:07 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:46:07 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:07 INFO - u'jsshell-linux-x86_64.zip'], 05:46:07 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:07 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:46:07 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:07 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:07 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:46:07 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:07 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:46:07 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:07 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:46:07 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:07 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:07 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:46:07 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:46:07 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:07 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:07 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:07 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:07 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:07 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:08 INFO - Downloaded 22528988 bytes. 05:46:08 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:08 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:08 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:10 INFO - Return code: 0 05:46:10 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:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:10 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:10 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:10 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:10 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:10 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:13 INFO - Downloaded 22528988 bytes. 05:46:13 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:13 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:46:13 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:14 INFO - Return code: 0 05:46:14 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:14 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:14 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:14 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:14 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:14 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:15 INFO - Downloaded 11131717 bytes. 05:46:15 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:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:15 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:15 INFO - Return code: 0 05:46:15 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:15 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:15 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:15 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:15 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:15 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:16 INFO - Downloaded 58362354 bytes. 05:46:16 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:16 INFO - mkdir: /builds/slave/test-pgo/properties 05:46:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:46:16 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:46:16 INFO - Contents: 05:46:16 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:16 INFO - Running post-action listener: _resource_record_post_action 05:46:16 INFO - Running post-action listener: set_extra_try_arguments 05:46:16 INFO - ##### 05:46:16 INFO - ##### Running populate-webroot step. 05:46:16 INFO - ##### 05:46:16 INFO - Running pre-action listener: _resource_record_pre_action 05:46:16 INFO - Running main action method: populate_webroot 05:46:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:16 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:46:16 INFO - '../fennec_ids.txt'], 05:46:16 INFO - 'tests': ['tcheck2']}, 05:46:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:16 INFO - '--tppagecycles', 05:46:16 INFO - '7'], 05:46:16 INFO - 'tests': ['tsvgm']}}, 05:46:16 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:46:16 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:16 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:16 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'tests': ['damp', 'tps']}, 05:46:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['damp', 'tps']}, 05:46:16 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:46:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:16 INFO - 'other': {'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other_l64': {'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'other_nol64': {'tests': ['a11yr', 05:46:16 INFO - 'ts_paint', 05:46:16 INFO - 'tpaint', 05:46:16 INFO - 'sessionrestore', 05:46:16 INFO - 'sessionrestore_no_auto_restore']}, 05:46:16 INFO - 'svgr': {'tests': ['tsvgx', 05:46:16 INFO - 'tsvgr_opacity', 05:46:16 INFO - 'tart', 05:46:16 INFO - 'tscrollx', 05:46:16 INFO - 'cart']}, 05:46:16 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tsvgx', 05:46:16 INFO - 'tsvgr_opacity', 05:46:16 INFO - 'tart', 05:46:16 INFO - 'tscrollx', 05:46:16 INFO - 'cart']}, 05:46:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'tests': ['tp5o']}, 05:46:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s'], 05:46:16 INFO - 'tests': ['tp5o']}, 05:46:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--xperf_path', 05:46:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:16 INFO - 'tests': ['tp5n']}, 05:46:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:16 INFO - 'talos_options': ['--e10s', 05:46:16 INFO - '--xperf_path', 05:46:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:16 INFO - 'tests': ['tp5n']}}, 05:46:16 INFO - 'talos.zip': {'path': '', 05:46:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:16 INFO - Running post-action listener: _resource_record_post_action 05:46:16 INFO - ##### 05:46:16 INFO - ##### Running create-virtualenv step. 05:46:16 INFO - ##### 05:46:16 INFO - Running pre-action listener: _resource_record_pre_action 05:46:16 INFO - Running main action method: create_virtualenv 05:46:16 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:46:16 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:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:46:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:17 INFO - Using real prefix '/usr' 05:46:17 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:46:17 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:19 INFO - Installing pip.................done. 05:46:19 INFO - Return code: 0 05:46:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:46:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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:19 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:19 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:19 INFO - Using env: {'DISPLAY': ':0', 05:46:19 INFO - 'HOME': '/home/cltbld', 05:46:19 INFO - 'LANG': 'en_US.UTF-8', 05:46:19 INFO - 'LANGUAGE': 'en_US:en', 05:46:19 INFO - 'LOGNAME': 'cltbld', 05:46:19 INFO - 'MAIL': '/var/mail/cltbld', 05:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:19 INFO - 'MOZ_NO_REMOTE': '1', 05:46:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:19 INFO - 'NO_EM_RESTART': '1', 05:46:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:19 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:19 INFO - 'SHELL': '/bin/bash', 05:46:19 INFO - 'SHLVL': '1', 05:46:19 INFO - 'TERM': 'linux', 05:46:19 INFO - 'TMOUT': '86400', 05:46:19 INFO - 'USER': 'cltbld', 05:46:19 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:19 INFO - '_': '/tools/buildbot/bin/python'} 05:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:19 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:19 INFO - Cleaning up... 05:46:19 INFO - Return code: 0 05:46:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:46:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:19 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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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:19 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:19 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:19 INFO - Using env: {'DISPLAY': ':0', 05:46:19 INFO - 'HOME': '/home/cltbld', 05:46:19 INFO - 'LANG': 'en_US.UTF-8', 05:46:19 INFO - 'LANGUAGE': 'en_US:en', 05:46:19 INFO - 'LOGNAME': 'cltbld', 05:46:19 INFO - 'MAIL': '/var/mail/cltbld', 05:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:19 INFO - 'MOZ_NO_REMOTE': '1', 05:46:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:19 INFO - 'NO_EM_RESTART': '1', 05:46:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:19 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:19 INFO - 'SHELL': '/bin/bash', 05:46:19 INFO - 'SHLVL': '1', 05:46:19 INFO - 'TERM': 'linux', 05:46:19 INFO - 'TMOUT': '86400', 05:46:19 INFO - 'USER': 'cltbld', 05:46:19 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:19 INFO - '_': '/tools/buildbot/bin/python'} 05:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:19 INFO - Downloading/unpacking psutil>=0.7.1 05:46:19 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:19 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:19 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:19 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:19 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:19 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:21 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:46:21 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:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:21 INFO - Installing collected packages: psutil 05:46:21 INFO - Running setup.py install for psutil 05:46:21 INFO - building 'psutil._psutil_linux' extension 05:46:21 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:21 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:21 INFO - building 'psutil._psutil_posix' extension 05:46:21 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:22 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:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:22 INFO - Successfully installed psutil 05:46:22 INFO - Cleaning up... 05:46:22 INFO - Return code: 0 05:46:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:46:22 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:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:22 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:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:22 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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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:22 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:22 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:22 INFO - Using env: {'DISPLAY': ':0', 05:46:22 INFO - 'HOME': '/home/cltbld', 05:46:22 INFO - 'LANG': 'en_US.UTF-8', 05:46:22 INFO - 'LANGUAGE': 'en_US:en', 05:46:22 INFO - 'LOGNAME': 'cltbld', 05:46:22 INFO - 'MAIL': '/var/mail/cltbld', 05:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:22 INFO - 'MOZ_NO_REMOTE': '1', 05:46:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:22 INFO - 'NO_EM_RESTART': '1', 05:46:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:22 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:22 INFO - 'SHELL': '/bin/bash', 05:46:22 INFO - 'SHLVL': '1', 05:46:22 INFO - 'TERM': 'linux', 05:46:22 INFO - 'TMOUT': '86400', 05:46:22 INFO - 'USER': 'cltbld', 05:46:22 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:22 INFO - '_': '/tools/buildbot/bin/python'} 05:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:46:22 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:22 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:22 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:22 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:22 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:22 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:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:46:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:46:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:24 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:24 INFO - Installing collected packages: mozsystemmonitor 05:46:24 INFO - Running setup.py install for mozsystemmonitor 05:46:24 INFO - Successfully installed mozsystemmonitor 05:46:24 INFO - Cleaning up... 05:46:24 INFO - Return code: 0 05:46:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:24 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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 05:46:24 INFO - 'HOME': '/home/cltbld', 05:46:24 INFO - 'LANG': 'en_US.UTF-8', 05:46:24 INFO - 'LANGUAGE': 'en_US:en', 05:46:24 INFO - 'LOGNAME': 'cltbld', 05:46:24 INFO - 'MAIL': '/var/mail/cltbld', 05:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:24 INFO - 'MOZ_NO_REMOTE': '1', 05:46:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:24 INFO - 'NO_EM_RESTART': '1', 05:46:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:24 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:24 INFO - 'SHELL': '/bin/bash', 05:46:24 INFO - 'SHLVL': '1', 05:46:24 INFO - 'TERM': 'linux', 05:46:24 INFO - 'TMOUT': '86400', 05:46:24 INFO - 'USER': 'cltbld', 05:46:24 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:24 INFO - '_': '/tools/buildbot/bin/python'} 05:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:24 INFO - Downloading/unpacking blobuploader==1.2.4 05:46:24 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:24 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:24 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:24 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:24 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:24 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:26 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:26 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:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46:26 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:26 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:26 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:26 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:26 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:26 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:26 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:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:46:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46:26 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:26 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:26 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:26 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:26 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:26 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:26 INFO - Downloading docopt-0.6.1.tar.gz 05:46:26 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:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:26 INFO - Running setup.py install for blobuploader 05:46:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:27 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:46:27 INFO - Running setup.py install for requests 05:46:27 INFO - Running setup.py install for docopt 05:46:27 INFO - Successfully installed blobuploader requests docopt 05:46:27 INFO - Cleaning up... 05:46:27 INFO - Return code: 0 05:46:27 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:27 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:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:27 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:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:27 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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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:27 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:27 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:27 INFO - Using env: {'DISPLAY': ':0', 05:46:27 INFO - 'HOME': '/home/cltbld', 05:46:27 INFO - 'LANG': 'en_US.UTF-8', 05:46:27 INFO - 'LANGUAGE': 'en_US:en', 05:46:27 INFO - 'LOGNAME': 'cltbld', 05:46:27 INFO - 'MAIL': '/var/mail/cltbld', 05:46:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:27 INFO - 'MOZ_NO_REMOTE': '1', 05:46:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:27 INFO - 'NO_EM_RESTART': '1', 05:46:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:27 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:27 INFO - 'SHELL': '/bin/bash', 05:46:27 INFO - 'SHLVL': '1', 05:46:27 INFO - 'TERM': 'linux', 05:46:27 INFO - 'TMOUT': '86400', 05:46:27 INFO - 'USER': 'cltbld', 05:46:27 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:27 INFO - '_': '/tools/buildbot/bin/python'} 05:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:27 INFO - Running setup.py (path:/tmp/pip-PYb1xG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:27 INFO - Running setup.py (path:/tmp/pip-2Ao_0L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:27 INFO - Running setup.py (path:/tmp/pip-hH7FJl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:27 INFO - Running setup.py (path:/tmp/pip-Eflt8m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:28 INFO - Running setup.py (path:/tmp/pip-lbJxB9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:28 INFO - Running setup.py (path:/tmp/pip-3s_Hus-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:28 INFO - Running setup.py (path:/tmp/pip-3fjAxF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:28 INFO - Running setup.py (path:/tmp/pip-TsDSIG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:28 INFO - Running setup.py (path:/tmp/pip-PIBCDZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:28 INFO - Running setup.py (path:/tmp/pip-UPetS7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:28 INFO - Running setup.py (path:/tmp/pip-30JVU1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:28 INFO - Running setup.py (path:/tmp/pip-jyqb_y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:28 INFO - Running setup.py (path:/tmp/pip-E2mITU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:28 INFO - Running setup.py (path:/tmp/pip-LZ88eG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:28 INFO - Running setup.py (path:/tmp/pip-XQfics-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:28 INFO - Running setup.py (path:/tmp/pip-G7mSNk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:28 INFO - Running setup.py (path:/tmp/pip-GfdT2c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:28 INFO - Running setup.py install for manifestparser 05:46:29 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Running setup.py install for mozcrash 05:46:29 INFO - Running setup.py install for mozdebug 05:46:29 INFO - Running setup.py install for mozdevice 05:46:29 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Running setup.py install for mozfile 05:46:29 INFO - Running setup.py install for mozhttpd 05:46:29 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Running setup.py install for mozinfo 05:46:29 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Running setup.py install for mozInstall 05:46:29 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Running setup.py install for mozleak 05:46:29 INFO - Running setup.py install for mozlog 05:46:29 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:46:29 INFO - Running setup.py install for moznetwork 05:46:30 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:46:30 INFO - Running setup.py install for mozprocess 05:46:30 INFO - Running setup.py install for mozprofile 05:46:30 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:46:30 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:46:30 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:46:30 INFO - Running setup.py install for mozrunner 05:46:30 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:46:30 INFO - Running setup.py install for mozscreenshot 05:46:30 INFO - Running setup.py install for moztest 05:46:30 INFO - Running setup.py install for mozversion 05:46:30 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:46:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:30 INFO - Cleaning up... 05:46:30 INFO - Return code: 0 05:46:30 INFO - Installing None 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', '-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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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', '-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:30 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: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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 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 - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:30 INFO - Running setup.py (path:/tmp/pip-Ym9erQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:31 INFO - Running setup.py (path:/tmp/pip-UxbPAr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:31 INFO - Running setup.py (path:/tmp/pip-07aJII-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:31 INFO - Running setup.py (path:/tmp/pip-_5S0CE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:31 INFO - Running setup.py (path:/tmp/pip-QTEJM_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:31 INFO - Running setup.py (path:/tmp/pip-HMe9Lw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:31 INFO - Running setup.py (path:/tmp/pip-ki2tCw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:31 INFO - Running setup.py (path:/tmp/pip-TJPUUL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:31 INFO - Running setup.py (path:/tmp/pip-SDfFNW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:31 INFO - Running setup.py (path:/tmp/pip-eG0zvi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:31 INFO - Running setup.py (path:/tmp/pip-IC3gcZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:31 INFO - Running setup.py (path:/tmp/pip-6DWN_5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:31 INFO - Running setup.py (path:/tmp/pip-t2Wn0W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:31 INFO - Running setup.py (path:/tmp/pip-l_2Ho6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:31 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:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:31 INFO - Running setup.py (path:/tmp/pip-ZhKYhp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:32 INFO - Running setup.py (path:/tmp/pip-KzHi2P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:32 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:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:32 INFO - Running setup.py (path:/tmp/pip-uSpSan-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:32 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:32 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:32 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:32 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:32 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:32 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:32 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:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:33 INFO - Downloading blessings-1.5.1.tar.gz 05:46:33 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:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:33 INFO - Installing collected packages: blessings 05:46:33 INFO - Running setup.py install for blessings 05:46:34 INFO - Successfully installed blessings 05:46:34 INFO - Cleaning up... 05:46:34 INFO - Return code: 0 05:46:34 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:46:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:46:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:46:34 INFO - Reading from file tmpfile_stdout 05:46:34 INFO - Current package versions: 05:46:34 INFO - argparse == 1.2.1 05:46:34 INFO - blessings == 1.5.1 05:46:34 INFO - blobuploader == 1.2.4 05:46:34 INFO - docopt == 0.6.1 05:46:34 INFO - manifestparser == 1.1 05:46:34 INFO - mozInstall == 1.12 05:46:34 INFO - mozcrash == 0.16 05:46:34 INFO - mozdebug == 0.1 05:46:34 INFO - mozdevice == 0.47 05:46:34 INFO - mozfile == 1.2 05:46:34 INFO - mozhttpd == 0.7 05:46:34 INFO - mozinfo == 0.9 05:46:34 INFO - mozleak == 0.1 05:46:34 INFO - mozlog == 3.1 05:46:34 INFO - moznetwork == 0.27 05:46:34 INFO - mozprocess == 0.22 05:46:34 INFO - mozprofile == 0.27 05:46:34 INFO - mozrunner == 6.11 05:46:34 INFO - mozscreenshot == 0.1 05:46:34 INFO - mozsystemmonitor == 0.0 05:46:34 INFO - moztest == 0.7 05:46:34 INFO - mozversion == 1.4 05:46:34 INFO - psutil == 3.1.1 05:46:34 INFO - requests == 1.2.3 05:46:34 INFO - wsgiref == 0.1.2 05:46:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:34 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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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:34 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:34 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:34 INFO - Using env: {'DISPLAY': ':0', 05:46:34 INFO - 'HOME': '/home/cltbld', 05:46:34 INFO - 'LANG': 'en_US.UTF-8', 05:46:34 INFO - 'LANGUAGE': 'en_US:en', 05:46:34 INFO - 'LOGNAME': 'cltbld', 05:46:34 INFO - 'MAIL': '/var/mail/cltbld', 05:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:34 INFO - 'MOZ_NO_REMOTE': '1', 05:46:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:34 INFO - 'NO_EM_RESTART': '1', 05:46:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:34 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:34 INFO - 'SHELL': '/bin/bash', 05:46:34 INFO - 'SHLVL': '1', 05:46:34 INFO - 'TERM': 'linux', 05:46:34 INFO - 'TMOUT': '86400', 05:46:34 INFO - 'USER': 'cltbld', 05:46:34 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:34 INFO - '_': '/tools/buildbot/bin/python'} 05:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:34 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:34 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:34 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:34 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:34 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:34 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:34 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:34 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:34 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:34 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:34 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:34 INFO - Cleaning up... 05:46:34 INFO - Return code: 0 05:46:34 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:34 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 0x12c2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14edc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, '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': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', '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:34 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:34 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:34 INFO - Using env: {'DISPLAY': ':0', 05:46:34 INFO - 'HOME': '/home/cltbld', 05:46:34 INFO - 'LANG': 'en_US.UTF-8', 05:46:34 INFO - 'LANGUAGE': 'en_US:en', 05:46:34 INFO - 'LOGNAME': 'cltbld', 05:46:34 INFO - 'MAIL': '/var/mail/cltbld', 05:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:34 INFO - 'MOZ_NO_REMOTE': '1', 05:46:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:34 INFO - 'NO_EM_RESTART': '1', 05:46:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:34 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:34 INFO - 'SHELL': '/bin/bash', 05:46:34 INFO - 'SHLVL': '1', 05:46:34 INFO - 'TERM': 'linux', 05:46:34 INFO - 'TMOUT': '86400', 05:46:34 INFO - 'USER': 'cltbld', 05:46:34 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:34 INFO - '_': '/tools/buildbot/bin/python'} 05:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:34 INFO - Downloading/unpacking jsonschema 05:46:34 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:34 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:34 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:34 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:34 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:34 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:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:36 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:36 INFO - Downloading/unpacking functools32 (from jsonschema) 05:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:36 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:36 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:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:36 INFO - warning: no files found matching '*.txt' 05:46:36 INFO - no previously-included directories found matching 'build' 05:46:36 INFO - no previously-included directories found matching 'dist' 05:46:36 INFO - no previously-included directories found matching '.git*' 05:46:36 INFO - Installing collected packages: jsonschema, functools32 05:46:36 INFO - Running setup.py install for functools32 05:46:36 INFO - warning: no files found matching '*.txt' 05:46:36 INFO - no previously-included directories found matching 'build' 05:46:36 INFO - no previously-included directories found matching 'dist' 05:46:36 INFO - no previously-included directories found matching '.git*' 05:46:36 INFO - Successfully installed jsonschema functools32 05:46:36 INFO - Cleaning up... 05:46:36 INFO - Return code: 0 05:46:36 INFO - Running post-action listener: _resource_record_post_action 05:46:36 INFO - Running post-action listener: _start_resource_monitoring 05:46:36 INFO - Starting resource monitoring. 05:46:36 INFO - ##### 05:46:36 INFO - ##### Running install step. 05:46:36 INFO - ##### 05:46:36 INFO - Running pre-action listener: _resource_record_pre_action 05:46:36 INFO - Running main action method: install 05:46:36 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:46:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:46:37 INFO - Reading from file tmpfile_stdout 05:46:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:46:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:46:37 INFO - Reading from file tmpfile_stdout 05:46:37 INFO - Output received: 05:46:37 INFO - Usage: mozinstall [options] installer 05:46:37 INFO - Options: 05:46:37 INFO - -h, --help show this help message and exit 05:46:37 INFO - -d DEST, --destination=DEST 05:46:37 INFO - Directory to install application into. [default: 05:46:37 INFO - "/builds/slave/test-pgo"] 05:46:37 INFO - --app=APP Application being installed. [default: firefox] 05:46:37 INFO - mkdir: /builds/slave/test-pgo/build/application 05:46:37 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:37 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:46:52 INFO - Reading from file tmpfile_stdout 05:46:52 INFO - Output received: 05:46:52 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:46:52 INFO - Running post-action listener: _resource_record_post_action 05:46:52 INFO - ##### 05:46:52 INFO - ##### Running run-tests step. 05:46:52 INFO - ##### 05:46:52 INFO - Running pre-action listener: _resource_record_pre_action 05:46:52 INFO - Running main action method: run_tests 05:46:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:46:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:46:52 INFO - Python 2.7.3 05:46:52 INFO - Return code: 0 05:46:52 INFO - grabbing minidump binary from tooltool 05:46:52 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:52 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 0x157ce50>, '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 0x157dd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157e1e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:46:52 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:46:52 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:46:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:52 INFO - Return code: 0 05:46:52 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:46:52 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:46:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:46:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:46:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:46:52 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-e10s', '--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-028', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:46:52 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-e10s --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-028 --webServer localhost 05:46:52 INFO - Using env: {'DISPLAY': ':0', 05:46:52 INFO - 'HOME': '/home/cltbld', 05:46:52 INFO - 'LANG': 'en_US.UTF-8', 05:46:52 INFO - 'LANGUAGE': 'en_US:en', 05:46:52 INFO - 'LOGNAME': 'cltbld', 05:46:52 INFO - 'MAIL': '/var/mail/cltbld', 05:46:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:46:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:52 INFO - 'MOZ_NO_REMOTE': '1', 05:46:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:52 INFO - 'NO_EM_RESTART': '1', 05:46:52 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:52 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:52 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:46:52 INFO - 'SHELL': '/bin/bash', 05:46:52 INFO - 'SHLVL': '1', 05:46:52 INFO - 'TERM': 'linux', 05:46:52 INFO - 'TMOUT': '86400', 05:46:52 INFO - 'USER': 'cltbld', 05:46:52 INFO - 'XDG_SESSION_COOKIE': 'b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196', 05:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:52 INFO - '_': '/tools/buildbot/bin/python'} 05:46:52 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-e10s', '--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-028', '--webServer', 'localhost'] with output_timeout 3600 05:46:52 INFO - mozversion INFO | application_buildid: 20151124043011 05:46:52 INFO - mozversion INFO | application_changeset: cf8cf1a039dd370d157b2afaa1fed719f1e34069 05:46:52 INFO - mozversion INFO | application_display_name: Nightly 05:46:52 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:52 INFO - mozversion INFO | application_name: Firefox 05:46:52 INFO - mozversion INFO | application_remotingname: firefox 05:46:52 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:46:52 INFO - mozversion INFO | application_vendor: Mozilla 05:46:52 INFO - mozversion INFO | application_version: 45.0a1 05:46:52 INFO - mozversion INFO | platform_buildid: 20151124043011 05:46:52 INFO - mozversion INFO | platform_changeset: cf8cf1a039dd370d157b2afaa1fed719f1e34069 05:46:52 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:46:52 INFO - mozversion INFO | platform_version: 45.0a1 05:46:52 INFO - 2015-11-24 05:46:52,355 DEBUG : using testdate: 1448372812 05:46:52 INFO - 2015-11-24 05:46:52,355 DEBUG : actual date: 1448372812 05:46:52 INFO - 2015-11-24 05:46:52,364 INFO : starting webserver on 'localhost:53454' 05:46:52 INFO - 2015-11-24 05:46:52,365 INFO : Starting test suite talos-linux64-ix-028.e 05:46:52 INFO - 2015-11-24 05:46:52,365 INFO : Starting test dromaeo_css 05:46:52 INFO - 2015-11-24 05:46:52,365 DEBUG : operating with platform_type : linux_ 05:46:52 INFO - 2015-11-24 05:46:52,366 INFO : Initialising browser for dromaeo_css test... 05:46:52 INFO - 2015-11-24 05:46:52,377 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpooV_fL/profile http://localhost:53454/getInfo.html 05:46:56 INFO - 2015-11-24 05:46:56,836 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:46:56 INFO - 2015-11-24 05:46:56,836 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:46:56 INFO - 2015-11-24 05:46:56,836 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:46:56 INFO - 2015-11-24 05:46:56,836 DEBUG : BROWSER_OUTPUT: __metrics 05:46:57 INFO - 2015-11-24 05:46:57,793 INFO : Browser initialized. 05:46:57 INFO - 2015-11-24 05:46:57,794 INFO : Running cycle 1/1 for dromaeo_css test... 05:46:57 INFO - 2015-11-24 05:46:57,794 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpooV_fL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:47:09 INFO - 2015-11-24 05:47:09,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 139259904 05:47:09 INFO - 2015-11-24 05:47:09,545 DEBUG : BROWSER_OUTPUT: 05:47:59 INFO - 2015-11-24 05:47:59,172 DEBUG : BROWSER_OUTPUT: 1448372879171 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:08 INFO - 2015-11-24 05:50:08,090 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53454/tests/dromaeo/cssquery-ext.html) 05:50:09 INFO - 2015-11-24 05:50:09,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 148942848 05:50:09 INFO - 2015-11-24 05:50:09,322 DEBUG : BROWSER_OUTPUT: 05:53:11 INFO - 2015-11-24 05:53:11,375 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/dromaeo/cssquery-ext.html (next: http://localhost:53454/tests/dromaeo/cssquery-jquery.html) 05:53:11 INFO - 2015-11-24 05:53:11,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 142192640 05:53:11 INFO - 2015-11-24 05:53:11,957 DEBUG : BROWSER_OUTPUT: 05:56:13 INFO - 2015-11-24 05:56:13,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53454/tests/dromaeo/cssquery-mootools.html) 05:56:14 INFO - 2015-11-24 05:56:14,228 DEBUG : BROWSER_OUTPUT: RSS: Main: 142581760 05:56:14 INFO - 2015-11-24 05:56:14,228 DEBUG : BROWSER_OUTPUT: 05:59:15 INFO - 2015-11-24 05:59:15,883 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53454/tests/dromaeo/cssquery-prototype.html) 05:59:16 INFO - 2015-11-24 05:59:16,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 141656064 05:59:16 INFO - 2015-11-24 05:59:16,491 DEBUG : BROWSER_OUTPUT: 06:02:18 INFO - 2015-11-24 06:02:18,774 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53454/tests/dromaeo/cssquery-yui.html) 06:02:19 INFO - 2015-11-24 06:02:19,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 141742080 06:02:19 INFO - 2015-11-24 06:02:19,369 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,683 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/dromaeo/cssquery-yui.html (next: http://localhost:53454/tests/dromaeo/cssquery-dojo.html) 06:05:21 INFO - 2015-11-24 06:05:21,934 DEBUG : BROWSER_OUTPUT: RSS: Main: 145723392 06:05:21 INFO - 2015-11-24 06:05:21,934 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,934 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:05:21 INFO - 2015-11-24 06:05:21,934 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:05:21 INFO - 2015-11-24 06:05:21,934 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:05:21 INFO - 2015-11-24 06:05:21,934 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:05:21 INFO - 2015-11-24 06:05:21,935 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3208.1498402923226;3209.4383482890503;3259.462188738858;3261.6575259597744;3282.179455136216;5276.551493123079;5484.890302193954;5494.120940649497;5507.9449205507935;5513.421090785465;5490.286262785842;5491.039068163065;5509.669419834802;5610.046292130338;5617.045102332603;4942.431620363668;5008.924866127011;5077.33994580704;5093.414257360414;5099.6685215460875;5019.924701129486;5088.669236499634;5133.101707201225;5185.481451854803;5191.688498690071;4880.170371036932;5022.573081288095;5080.79676812927;5109.591232701394;5226.947730522703;4962.057209130254;5057.721825299607;5063.215201643751;5071.46749591295;5075.720835354054;5031.572316353115;5057.721825299607;5084.3898732152375;5085.974570127163;5112.744362781883;5107.004134193834;5220.425753167148;5279.1289437243195;5348;5411.026015317241;5953.702314884238;5957.970210148965;5961.791337303217;5967.6419414835245;6013.939860601382;6004.699765011732;6009.789657362015;6015.218021657157;6015.969920150371;6029.909551356734;5343.225232341328;5436.7553460094;5453.345598528202;5569.526590239875;5594.9720251398485;6089.238845144357;6109.450149486567;6116.327204007555;6117.174181485531;6137.1101190328045;5930.347661757203;5935.881282374328;5949.464548190597;5952.077427998667;5954.970225148846;5783.508401785895;5790.652560846363;5817.825465236049;5867.471927526543;5887;5903.468687818117;5941.049432090931;5974.970125149346;5978.342382338026;6017.759289628453;5962;5968.104784282393;5985.132155837379;5995.490383317467;5999.820005399845;4405.823767049346;4508.07584445188;4530.750808705487;4593.012502312019;4620.630349572026;4053.0880551875593;4081.142959978438;4083.547057590818;4117.464729585195;4118.299889018874;5694.601377903507;5722.227499287626;5726.856828579319;5738.254026976467;5759.913601295984;5717.342505611825;5884.470397664145;5900.881982360329;5984.042553191469;6049.334573196945;5717.171010203498;5739.827805165852;5747.741351639186;5772.509336706343;5780.913286300709;5226.660267082628;5228.712420816892;5237.869053273698;5260.184671375943;5282.260483532308;5904.763809447574;6047.15339852421;6105.938940610626;6217.378262173746;6226.221722284805;5949.583529152917;5960.940390596039;5962.761489540369;5976.820695379146;6018.7291571878495;6126.938730612816;6163;6173.969130154321;6185.072239164161;6196.318404975539;5735.799247026334;5805.622634528742;5837.066069428871;5882.852928676817;5917.5857689961285;5940.138679891305;5940.702964851654;5992.370801065826;6002.579819412599;6009;5591.860203494945;5783.363829978614;5824.359320474659;5830.766769329349;5845.152452894392;5663.801766938137;5711.657300561979;5767.9134812977;5791.276090488689;5850.853728656647;5701.942980570141;5724.914126288026;5737.397573254915;5744.971275143597;5759.971200143972;5686.4029276926985;5739.253896993531;5800.796972105956;5831.79588714393;5852.707364631837;5757.942420575908;5790.305163380422;5827.271591051119;5827.970860145672;5843.737031833492 06:05:21 INFO - 2015-11-24 06:05:21,936 DEBUG : BROWSER_OUTPUT: |1;ext.html;541.2666429615582;592.2389729197981;595.6604735300879;601.3445344574415;605.6124080588421;36493.08767280819;36734.265314693694;37107.628923710756;37150.44274335887;37153.81423092884;40965.795171024176;41581.96045098874;41596.58403415965;41650;41744.58255417445;8649.783755406103;8689.7827554311;8776.780580485507;8803.95598022009;8835.337349698766;8580.313574913991;8669.653213871437;8682.218600325969;8685.218330350268;8695.652173913037;8696.17386348296;8728.039915609277;8779.473231606093;8803.73588792334;8812.339074569401;8674.913250867474;8748.08145144757;8752.387332886732;8755.868661970075;8760.649574017032;8602.053774084845;8731.126887311219;8738.213560779497;8754.73735787921;8757.2994160467;38790.80604596987;39104.413433798516;39348.60651393478;39362.60637393618;39416.408753868716;3292.1275861896615;3293.917652058694;3301.1747063234193;3610.4584312353095;3985.8422564898037;29276;29710.40579188426;29935.70064299351;30009.549856752164;30088.097357079103;3782.7919464429447;3938.0548668319684;3990.142119444304;4000.4799376080923;4004.4193591929006;264599;266993;267381;267463;267791.9999999981;50811;52224.738876305375;56829;57175.714121429955;58101.4189858096;59222.40777592169;59507.10739338913;59534.40465595375;59769;59865.80268394608;14507.92746036263;14666.120032798066;14716.969812113264;14735.26323683833;14739.557813265405;10849;10857.619983300547;10887.183461240438;10891.292066015683;10899.673009809718;18490.815091849177;18621.44135675932;18642.53393665142;18694;18757.624847502975;7505.962470187614;7705.807354816175;7788.727394541163;7809.609519524001;7928.3260922821055;947.8625599288065;989.7080361293292;1032.9741756455999;1078.369154044877;1080.2168427889717;28024.579631305547;28852.134435966953;28900.132996010154;28956.42087158245;29211.415771684446;1059.8569193158978;1087.429099722636;1143.2340331977596;1157.0280963990288;1200.6278053803348;14919.104853708595;14929.776053359208;15134.924325378302;15192;15374.538763837103;313131;318061;318659;319517;319659;50993.49006509961;51025.48974510207;51151.48848511542;51279;51393.486065139616;1317.6639956810952;1325.3307079924577;1340.0619566303626;1348.2315080404076;1350.3315858649835;27148.32129196786;27688.30779230455;27994.44011119807;28382.716172838835;28821.135365939055;43359.783201083796;43504.564954350055;43576.34635480407;43792;43845;208504;208886;210411;210615;210848;29316.7068329314;31841.203969900936;31948.52077218797;32193.51709724403;32275;38062;38659.2268154641;39048;39099.218015640094;39187.216255675296;34393.828030860685;34813.303733925684;34815.47776783401;35259;35365.29269414649;33818.46361522945;35101.26132972118;35552.46671299984;35761.64238357583;35968.460473092404;32684.836575816968;33092.83453582717;33252.16869578183;33257;33594.49608255829;31087.84456077705;31962.36075278528;32409.679414287446;32778.344433111684;32886.34227315488;24747.87626061858;25003.12489062874;25030.123945661813;25271.87364063168;25417.87291063607 06:05:21 INFO - 2015-11-24 06:05:21,939 DEBUG : BROWSER_OUTPUT: |2;jquery.html;349.6486031538304;350.21901160412295;351.95776506819186;352.1548284118117;356.2198784661591;16501.257443415045;17163.656726865454;17894.194761235743;17899.642007159848;18521.536961575966;11292.774144517125;11330.320180789158;11347.659570212907;11889.286642801439;16429.342826286935;4892.339534162887;4893.119238537062;4959.801607935697;4988.575971042465;5027.673201241926;4924.70451772893;4931.457539670633;4933.629977751683;4949.752512374385;4959.826406075788;3705.147816002321;4954.801807927679;4975.800967961295;5548.55611551075;5946.048632218846;5868.853278668024;5909.852253693649;5923.911141332862;5924.970375148118;5926.822195334125;4974;5092.414372347191;5135.204043373283;5150.356205474316;5289.312389389354;12949.741005179938;13097.279649619362;19083.809161908343;19856.900715496475;20622;2115.608612406694;2193.100828660242;2272.4659704969317;2306.342692332677;2309.0648287443446;9510.904890951071;9615.278854085902;9981.600735970553;9997.400155990665;12290.446929888176;1845.5570663040908;1903.7715474143054;1941.9126139323623;1942.1260432805293;1960.3334866145578;61907;64939.99999999953;65110.674446627934;66334.66832665855;66874.33125668729;10041.246906482043;10326.380417174843;10360.533775980099;10431.21765867563;10518.947405262925;13530.120542164728;13549;13618.387172577257;13656.522021729192;13664.93167534156;7471.3649339806725;7484.812879678052;7639.885401718979;7695.653695583712;7729.613519324012;7311.488195826348;7339.229380915034;7360.33756961876;7361.411087113018;7397.18630950595;4402;4434.534373890695;4444.799984000663;4485.708428952173;4486.1252055849445;1775.5738622730453;1863.1615772902248;1869.5980364221757;1884.4911873794;1907.9141438635017;431.7733190075236;433.8112920879428;437.78767297860514;467.7731300319342;889.208604342136;10667.253292269466;10717.678469645924;10774;10780.191485638608;10996.890031099587;451.62740738890545;457.636179237507;457.8695071904491;459.10932790386545;483.2606112647655;5861.41385861419;5950.851228719317;5972.163897054415;5995.160677505153;6027.427394397525;557401;564438;565489;566016;566278;82458.58770706228;85324;86387;86509.56745216233;86870.56564717263;1163.4880652512868;1164.7204670879098;1176.9058475321954;1192.9821052684217;1197.0961923747689;15900.204989750697;15901.681966360838;15931.8406815935;15932.761008584648;16439.424620138238;33879.830600847825;34430.82784586061;35169.1207719809;35271.294574108884;35564.644353556134;46874;46995.76502117467;47596.7620161897;48448.757756210995;52025;9725.270604704534;13324.733505329645;13486.797698034734;13599.388027538587;13782.517611883535;18435.907820460812;18763.718544221574;18828.341008064654;19338.4198474046;19404.611907761482;12709.682257943625;15480.303386347416;15765.921170394075;15896.76154857655;15975.68048639044;12703.301318427206;14345.282735863373;14347.56957291283;15177.392904283704;15718;15590.9220453897;15701.057936523845;16050.117243551716;16423.096729679983;16440.260188291795;14828.85171148318;15078.24608769579;15082.924585377441;15193.696126077195;15227.16250606183;15574.377024918876;15686.294116765;15706.842931570538;16121.435749748702;16143.354265829235 06:05:21 INFO - 2015-11-24 06:05:21,941 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1437.971240575188;1713.3061110250349;1758.9296428142875;1765.6115654555992;1786.0980204996479;7225.169105552863;7362.5950572718475;7630.084389873209;7895.921040789591;7905.209479052099;7631.503952243101;7657.4639775215755;7702.152763196044;7721.613919304026;7731.884021739665;2256.7291924968986;2285.9771402286015;2287.2795069553035;2299.758130609469;2302.021640802652;2307.849989750669;2323.024329781496;2343.5664402085586;2369.6327069304198;2371.632396978471;2111.7465904091455;2154.3213887625348;2164.729408823897;2264.1283106004225;2301.1945818963395;2294.8737819419925;2308.2613563659634;2352.811775058;2353.635186546088;2354.540864531417;2286.085565773687;2375.0737212487143;2375.121205154087;2381.618940969454;2387.937367871298;7702.9229707703325;7729.227077292282;7731.188225236382;7762.45662803604;7776.65005074773;4742.478327383985;4802.495737947535;4823.565879070866;4847.903041939141;4848.248521479177;3154.5899033125547;3160.225744692542;3249.171461277359;3270.8201048942296;3283.5074738789135;978.2467500024941;991.3011327014437;992.0525897767615;1004.6684594083935;1012.1700205831221;10419;10492.003259690317;10506.632267870664;10511.94744026275;10532.578696852193;4200.306949353384;4259.254630439661;4289.806958686867;4331.501877284152;4348.586884245991;8590.91409085901;8591.269742071869;8634.78413039679;8636.265917397091;8650.35122365825;4726.763661816895;4751.263554149113;4751.477337492873;4768.213244814636;4771.021940502189;4322.15717935006;4342.370356298382;4349.956500435019;4358.259095953696;4362.345648152739;3505.158761897126;3528.347255757693;3537;3542.929141417157;3552.8934131976084;1084.5553323137476;1097.9341239525654;1102.239454776202;1111.3054341036852;1120.2830188679252;952.1288021460286;1004.829179039565;1071.373246650718;1192.4059948484892;1343.5431953135785;7005.894911576331;7154.35610795031;7155.320244576861;7172;7181.748638797617;619.2847261413075;620.2804746494045;630.9968450157812;631.1826185090227;768.588804989328;5531.280933478622;5608.383077861432;5710.65736055838;5732.140178973104;5768;9065.138811812867;9228.815423691489;9305.162535371848;9311.674091406769;9327.253819694408;8911.955440222888;9185.632574696938;9187.356885017985;9215.49314787693;9263.722088337227;7117.501774875709;7180.353768160786;7186.209516953131;7202.819929501804;7271.454640901953;6892.276310987274;6929.792106236821;6975.128108986377;6994.6852391643515;6997.26528714498;7971.242731940456;8042.195780421911;8142.633581488965;8228.465149765247;8277.917220827956;8241.587920604066;8251;8332.458390204618;8437.071922088688;8492.74521764348;6855.108835851307;6891.4486841053595;6928.861422771617;6935.895961560682;6941.861162776615;7391.741289054858;7450.739224127129;7461.962690186731;7475.88786168197;7477.439192060726;7019.894701579583;7053.576785392687;7078.681459334445;7215.314545118205;7247.094113235846;6482.189726284215;6685.498587605852;6746.628935408599;6908.067410899665;7115.039469671735;7004.474664400293;7020.7893763187185;7025.754098606524;7043.119610048744;7049.154101507852;6370.203724534434;6529.869402611826;6533.542652014314;6546.738130474728;6548.6070835748105;6398.552101353047;6426.518011149089;6468.838279043061;6502.284748677547;6505.967470162808 06:05:21 INFO - 2015-11-24 06:05:21,942 DEBUG : BROWSER_OUTPUT: |4;prototype.html;335.2122512096573;344.1173390253998;344.8706734974385;345.9654034596539;353.5845381676531;6169;6216.129741836146;6219.284782250043;6307.180066591337;6432.42108210261;6214.03682429223;6249.468795152417;6254.43710066094;6258.906116408258;6265.624062556239;4708.929366059512;4728.787204575802;4742.288656701497;4745.549172828576;4750.406199225097;4731.668783185179;4746.715197088169;4750.4299484061785;4760.047990401916;4761.095391875532;4601.907961840761;4619.099275641252;4658.883527911812;4687.929681054787;4692.5072867348945;4631.652626053059;4646.372739680134;4663.393758811367;4671.205894997858;4671.9955209629925;4003.4795476588147;4031.8992025199314;4035.7982100895133;4055.452513910614;4069.409935559327;5808.506276966463;5819.592628516006;5840.269966254218;5840.591158618856;5852.912206316909;5320.5211530962015;5437.510624043815;5437.510624043815;5483.259759932398;5489.1217405214975;6561.639109848957;6567.244766851778;6568.901466478007;6575.11235983141;6575.276719560845;4490.281554951192;4491.079328737601;4501.212287849646;4501.7524036178;4539.614132798716;6759.290274521154;6779.15260592426;6825.965870170618;6868;6875.209350924658;4574.6569007323915;4610.654200934943;4618.838340658061;4623.468301145344;4624.05206932578;4649.3026046093355;4665.510121437268;4685.812567497262;4689.132510485571;4691.038337140878;5394.64934779246;5406.459354064641;5409.188621706697;5413.052715774724;5415.837524874181;6476.996065609838;6600.702968366339;6608.438282745924;6626.536142470077;6651.73393064272;1636.2309714434198;1659.8506134447716;1660.9750853737203;1664.6504234110973;1667;1097.863711059066;1099.1920938110482;1108.3128460354633;1113.465536542448;1118.3122379736403;6383.553151279459;6403.551751377452;6407.871842563122;6411.134496842867;6427.260865000491;6739.056532085512;6743.5953842769595;6746;6757.966210169016;6803.65981700913;6248.375162483715;6378.904316435257;6381.489480841615;6416.711247993758;6447.645379504079;3650.3976843821;3700.3339398907915;3715.7213209009433;3720.088578298334;3734.4958430612064;7157.069580954442;7157.57054576727;7179.676914538858;7190.352868241884;7196.028536147658;6888.827779305558;7052.294770522906;7060.7881763547175;7062;7091.503594748318;7159.319864612853;7160.283971602902;7179.2820717928835;7190.496665233383;7197.136343638692;6796.286389929184;6839.760608378882;6840.700266949264;6846;6857.074294970115;6607.636579988147;6784.694688739118;6785.185777706707;6789;6793.422559082534;7127.322904324081;7143.178534468602;7152.463565232524;7161.176464706417;7184.1019872515935;4657.81225787422;4741.336212930261;4743.051389722138;4749.857504274877;4769.928451073168;6649.537101837588;6734.6632668367365;6743.629100399329;6745;6773.288804675635;6562.475001999924;6563.638999855054;6567.784959782455;6568.540202185801;6594.538382313192;6445.903311450238;6558.245801732862;6573.868522629617;6579.276279609335;6579.407853293226;6429.357064293533;6431.774887878902;6433.453156481564;6434.195725534309;6436.292007879223;6548.967255163694;6573.5398522103;6605.702743376463;6617.569857959217;6619;6340.873182536416;6440.967795161182;6447.9355206447335;6451.483881289391;6454.580452270588 06:05:21 INFO - 2015-11-24 06:05:21,942 DEBUG : BROWSER_OUTPUT: |5;yui.html;715.3132992327365;761.6534476813049;816.6610856494555;825.1830687619258;849.3757088539926;13778.311084445775;14550.927245363784;14590.197539135343;14597.270136493173;14627.414903403864;12868.67828304293;12902.548410805624;12920.612381628544;13511.391987360568;13662.521811736591;805.4844899264475;814.7596459044596;824.3116997307226;824.4599787139404;826.1944604011101;804.3967024731452;815.0789607743271;815.9428839981204;816.3673153306175;820.0733171516179;859.2867919626694;862.9784255393603;866.6143566113083;867.8828358171629;873.698573991975;860.8321377331391;863.1196179896501;863.386995233385;864.0193380513163;868.7220089571372;628.7485005997637;630.6531407725732;631.8104568629393;631.9557630965834;632.4371309534517;12079.758404831942;12176.695582610506;12229.715879832667;12256.325902075383;12299.938500307531;10872.402017889013;10965.287256328393;11059.115270778319;11100.61147859821;11242.550297988071;9772.95113524435;9809.950950245204;9827.459489728062;9876.259280553988;9907.752306192331;3383.3571621391857;3497.562804649419;3591.964080359189;3606.2607165531003;3613.49411082446;47900.28149577759;47990.5200947993;48589;49386;49593.25610115815;9923.900760992297;10018.799624007479;10079.143272821892;10158.39049657023;10318.587256509805;18037.278508859497;18118.728219076726;18142.183601737688;18185.7272140918;18191.27234910615;7389.187189409161;7435.1449583297535;7521.0974683038385;7653.502522336031;7833.099193592696;4273.18809426208;4457.465104187453;4535.9773201133785;4577.244754615518;4600.539946005372;2962.4223276460884;2988.237999310162;2989.6845388029196;2992.5062364710016;2995.1164406500134;575.8646718021262;582.8076734677546;583.3000399520507;592.9940700593025;596.5257620191958;568.156287912458;676.9661516924134;677.1975209376905;679.3987321220756;681.1383599746325;9159.221466175317;9860.654877079267;9991.927615972518;10018.82004757153;10052.095311422006;663.1677245057422;691.2292793535172;691.3639451704444;693.2686016252801;717.6519388096768;4169.332906734908;4392.846250381222;4414.889627759268;4470.351798989193;4496.258117410591;56865.71567142221;61174.69412652908;61474.38525614776;61546.384536154066;61666.075008874905;53476.19785703217;54023.45976540263;54664.18003729947;55193.896122077334;55489.44510554923;44526.77736611296;45823;48095;48108;48805;8821.250193733604;8834.51410172421;9163.450192988443;9212;9292.767680808034;35044.47433288554;35664.82167589146;35902.820485897406;36178.63821361752;36249.45625815668;40654.1869162621;41254.79372603118;43198.35202472028;45276.32085518654;45396.773016134706;11560.13299002595;11564.363959982282;11651.30092194471;11714.531418743154;11774.587889424172;12611.369431528203;12664.113512054068;12698.174618649758;12761.808572871229;12958;11819.40902954866;12034.097442692007;12137.635870923887;12475.500979961063;12476.688082798002;11684.357360345262;11898.398388415506;12105.334206618369;12134.635960921187;12561.497540098293;11427;11625;11633.76732465329;12094.395280235776;12111.273323600612;9145.222656074307;9158.862617060882;9291.396059256127;9590.904090959;9658.613655453984;8878.467291962503;9083.355308658136;9088.363814532919;9328.347015708836;9679.806403871742 06:05:21 INFO - 2015-11-24 06:05:21,942 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:05:21 INFO - 2015-11-24 06:05:21,942 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,313 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: __startTimestamp1448373921932__endTimestamp 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,944 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5525.39 Median:5747.74 stddev:624.64 (10.9%) stddev-sans-first:599.53 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: Values: 3208.1 3209.4 3259.5 3261.7 3282.2 5276.6 5484.9 5494.1 5507.9 5513.4 5490.3 5491.0 5509.7 5610.0 5617.0 4942.4 5008.9 5077.3 5093.4 5099.7 5019.9 5088.7 5133.1 5185.5 5191.7 4880.2 5022.6 5080.8 5109.6 5226.9 4962.1 5057.7 5063.2 5071.5 5075.7 5031.6 5057.7 5084.4 5086.0 5112.7 5107.0 5220.4 5279.1 5348.0 5411.0 5953.7 5958.0 5961.8 5967.6 6013.9 6004.7 6009.8 6015.2 6016.0 6029.9 5343.2 5436.8 5453.3 5569.5 5595.0 6089.2 6109.5 6116.3 6117.2 6137.1 5930.3 5935.9 5949.5 5952.1 5955.0 5783.5 5790.7 5817.8 5867.5 5887.0 5903.5 5941.0 5975.0 5978.3 6017.8 5962.0 5968.1 5985.1 5995.5 5999.8 4405.8 4508.1 4530.8 4593.0 4620.6 4053.1 4081.1 4083.5 4117.5 4118.3 5694.6 5722.2 5726.9 5738.3 5759.9 5717.3 5884.5 5900.9 5984.0 6049.3 5717.2 5739.8 5747.7 5772.5 5780.9 5226.7 5228.7 5237.9 5260.2 5282.3 5904.8 6047.2 6105.9 6217.4 6226.2 5949.6 5960.9 5962.8 5976.8 6018.7 6126.9 6163.0 6174.0 6185.1 6196.3 5735.8 5805.6 5837.1 5882.9 5917.6 5940.1 5940.7 5992.4 6002.6 6009.0 5591.9 5783.4 5824.4 5830.8 5845.2 5663.8 5711.7 5767.9 5791.3 5850.9 5701.9 5724.9 5737.4 5745.0 5760.0 5686.4 5739.3 5800.8 5831.8 5852.7 5757.9 5790.3 5827.3 5828.0 5843.7 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43370.23 Median:35180.13 stddev:70093.00 (199.2%) stddev-sans-first:70215.86 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: Values: 541.3 592.2 595.7 601.3 605.6 36493.1 36734.3 37107.6 37150.4 37153.8 40965.8 41582.0 41596.6 41650.0 41744.6 8649.8 8689.8 8776.8 8804.0 8835.3 8580.3 8669.7 8682.2 8685.2 8695.7 8696.2 8728.0 8779.5 8803.7 8812.3 8674.9 8748.1 8752.4 8755.9 8760.6 8602.1 8731.1 8738.2 8754.7 8757.3 38790.8 39104.4 39348.6 39362.6 39416.4 3292.1 3293.9 3301.2 3610.5 3985.8 29276.0 29710.4 29935.7 30009.5 30088.1 3782.8 3938.1 3990.1 4000.5 4004.4 264599.0 266993.0 267381.0 267463.0 267792.0 50811.0 52224.7 56829.0 57175.7 58101.4 59222.4 59507.1 59534.4 59769.0 59865.8 14507.9 14666.1 14717.0 14735.3 14739.6 10849.0 10857.6 10887.2 10891.3 10899.7 18490.8 18621.4 18642.5 18694.0 18757.6 7506.0 7705.8 7788.7 7809.6 7928.3 947.9 989.7 1033.0 1078.4 1080.2 28024.6 28852.1 28900.1 28956.4 29211.4 1059.9 1087.4 1143.2 1157.0 1200.6 14919.1 14929.8 15134.9 15192.0 15374.5 313131.0 318061.0 318659.0 319517.0 319659.0 50993.5 51025.5 51151.5 51279.0 51393.5 1317.7 1325.3 1340.1 1348.2 1350.3 27148.3 27688.3 27994.4 28382.7 28821.1 43359.8 43504.6 43576.3 43792.0 43845.0 208504.0 208886.0 210411.0 210615.0 210848.0 29316.7 31841.2 31948.5 32193.5 32275.0 38062.0 38659.2 39048.0 39099.2 39187.2 34393.8 34813.3 34815.5 35259.0 35365.3 33818.5 35101.3 35552.5 35761.6 35968.5 32684.8 33092.8 33252.2 33257.0 33594.5 31087.8 31962.4 32409.7 32778.3 32886.3 24747.9 25003.1 25030.1 25271.9 25417.9 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29836.78 Median:34155.33 stddev:92248.00 (270.1%) stddev-sans-first:92480.21 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: Values: 349.6 350.2 352.0 352.2 356.2 16501.3 17163.7 17894.2 17899.6 18521.5 11292.8 11330.3 11347.7 11889.3 16429.3 4892.3 4893.1 4959.8 4988.6 5027.7 4924.7 4931.5 4933.6 4949.8 4959.8 3705.1 4954.8 4975.8 5548.6 5946.0 5868.9 5909.9 5923.9 5925.0 5926.8 4974.0 5092.4 5135.2 5150.4 5289.3 12949.7 13097.3 19083.8 19856.9 20622.0 2115.6 2193.1 2272.5 2306.3 2309.1 9510.9 9615.3 9981.6 9997.4 12290.4 1845.6 1903.8 1941.9 1942.1 1960.3 61907.0 64940.0 65110.7 66334.7 66874.3 10041.2 10326.4 10360.5 10431.2 10518.9 13530.1 13549.0 13618.4 13656.5 13664.9 7471.4 7484.8 7639.9 7695.7 7729.6 7311.5 7339.2 7360.3 7361.4 7397.2 4402.0 4434.5 4444.8 4485.7 4486.1 1775.6 1863.2 1869.6 1884.5 1907.9 431.8 433.8 437.8 467.8 889.2 10667.3 10717.7 10774.0 10780.2 10996.9 451.6 457.6 457.9 459.1 483.3 5861.4 5950.9 5972.2 5995.2 6027.4 557401.0 564438.0 565489.0 566016.0 566278.0 82458.6 85324.0 86387.0 86509.6 86870.6 1163.5 1164.7 1176.9 1193.0 1197.1 15900.2 15901.7 15931.8 15932.8 16439.4 33879.8 34430.8 35169.1 35271.3 35564.6 46874.0 46995.8 47596.8 48448.8 52025.0 9725.3 13324.7 13486.8 13599.4 13782.5 18435.9 18763.7 18828.3 19338.4 19404.6 12709.7 15480.3 15765.9 15896.8 15975.7 12703.3 14345.3 14347.6 15177.4 15718.0 15590.9 15701.1 16050.1 16423.1 16440.3 14828.9 15078.2 15082.9 15193.7 15227.2 15574.4 15686.3 15706.8 16121.4 16143.4 06:05:21 INFO - 2015-11-24 06:05:21,945 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,947 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5396.91 Median:6504.13 stddev:2766.89 (42.5%) stddev-sans-first:2758.65 06:05:21 INFO - 2015-11-24 06:05:21,947 DEBUG : BROWSER_OUTPUT: Values: 1438.0 1713.3 1758.9 1765.6 1786.1 7225.2 7362.6 7630.1 7895.9 7905.2 7631.5 7657.5 7702.2 7721.6 7731.9 2256.7 2286.0 2287.3 2299.8 2302.0 2307.8 2323.0 2343.6 2369.6 2371.6 2111.7 2154.3 2164.7 2264.1 2301.2 2294.9 2308.3 2352.8 2353.6 2354.5 2286.1 2375.1 2375.1 2381.6 2387.9 7702.9 7729.2 7731.2 7762.5 7776.7 4742.5 4802.5 4823.6 4847.9 4848.2 3154.6 3160.2 3249.2 3270.8 3283.5 978.2 991.3 992.1 1004.7 1012.2 10419.0 10492.0 10506.6 10511.9 10532.6 4200.3 4259.3 4289.8 4331.5 4348.6 8590.9 8591.3 8634.8 8636.3 8650.4 4726.8 4751.3 4751.5 4768.2 4771.0 4322.2 4342.4 4350.0 4358.3 4362.3 3505.2 3528.3 3537.0 3542.9 3552.9 1084.6 1097.9 1102.2 1111.3 1120.3 952.1 1004.8 1071.4 1192.4 1343.5 7005.9 7154.4 7155.3 7172.0 7181.7 619.3 620.3 631.0 631.2 768.6 5531.3 5608.4 5710.7 5732.1 5768.0 9065.1 9228.8 9305.2 9311.7 9327.3 8912.0 9185.6 9187.4 9215.5 9263.7 7117.5 7180.4 7186.2 7202.8 7271.5 6892.3 6929.8 6975.1 6994.7 6997.3 7971.2 8042.2 8142.6 8228.5 8277.9 8241.6 8251.0 8332.5 8437.1 8492.7 6855.1 6891.4 6928.9 6935.9 6941.9 7391.7 7450.7 7462.0 7475.9 7477.4 7019.9 7053.6 7078.7 7215.3 7247.1 6482.2 6685.5 6746.6 6908.1 7115.0 7004.5 7020.8 7025.8 7043.1 7049.2 6370.2 6529.9 6533.5 6546.7 6548.6 6398.6 6426.5 6468.8 6502.3 6506.0 06:05:21 INFO - 2015-11-24 06:05:21,947 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,947 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5508.69 Median:6359.89 stddev:1679.07 (26.4%) stddev-sans-first:1638.26 06:05:21 INFO - 2015-11-24 06:05:21,948 DEBUG : BROWSER_OUTPUT: Values: 335.2 344.1 344.9 346.0 353.6 6169.0 6216.1 6219.3 6307.2 6432.4 6214.0 6249.5 6254.4 6258.9 6265.6 4708.9 4728.8 4742.3 4745.5 4750.4 4731.7 4746.7 4750.4 4760.0 4761.1 4601.9 4619.1 4658.9 4687.9 4692.5 4631.7 4646.4 4663.4 4671.2 4672.0 4003.5 4031.9 4035.8 4055.5 4069.4 5808.5 5819.6 5840.3 5840.6 5852.9 5320.5 5437.5 5437.5 5483.3 5489.1 6561.6 6567.2 6568.9 6575.1 6575.3 4490.3 4491.1 4501.2 4501.8 4539.6 6759.3 6779.2 6826.0 6868.0 6875.2 4574.7 4610.7 4618.8 4623.5 4624.1 4649.3 4665.5 4685.8 4689.1 4691.0 5394.6 5406.5 5409.2 5413.1 5415.8 6477.0 6600.7 6608.4 6626.5 6651.7 1636.2 1659.9 1661.0 1664.7 1667.0 1097.9 1099.2 1108.3 1113.5 1118.3 6383.6 6403.6 6407.9 6411.1 6427.3 6739.1 6743.6 6746.0 6758.0 6803.7 6248.4 6378.9 6381.5 6416.7 6447.6 3650.4 3700.3 3715.7 3720.1 3734.5 7157.1 7157.6 7179.7 7190.4 7196.0 6888.8 7052.3 7060.8 7062.0 7091.5 7159.3 7160.3 7179.3 7190.5 7197.1 6796.3 6839.8 6840.7 6846.0 6857.1 6607.6 6784.7 6785.2 6789.0 6793.4 7127.3 7143.2 7152.5 7161.2 7184.1 4657.8 4741.3 4743.1 4749.9 4769.9 6649.5 6734.7 6743.6 6745.0 6773.3 6562.5 6563.6 6567.8 6568.5 6594.5 6445.9 6558.2 6573.9 6579.3 6579.4 6429.4 6431.8 6433.5 6434.2 6436.3 6549.0 6573.5 6605.7 6617.6 6619.0 6340.9 6441.0 6447.9 6451.5 6454.6 06:05:21 INFO - 2015-11-24 06:05:21,949 DEBUG : BROWSER_OUTPUT: 06:05:21 INFO - 2015-11-24 06:05:21,949 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14065.63 Median:48101.50 stddev:16418.63 (34.1%) stddev-sans-first:16434.08 06:05:21 INFO - 2015-11-24 06:05:21,949 DEBUG : BROWSER_OUTPUT: Values: 715.3 761.7 816.7 825.2 849.4 13778.3 14550.9 14590.2 14597.3 14627.4 12868.7 12902.5 12920.6 13511.4 13662.5 805.5 814.8 824.3 824.5 826.2 804.4 815.1 815.9 816.4 820.1 859.3 863.0 866.6 867.9 873.7 860.8 863.1 863.4 864.0 868.7 628.7 630.7 631.8 632.0 632.4 12079.8 12176.7 12229.7 12256.3 12299.9 10872.4 10965.3 11059.1 11100.6 11242.6 9773.0 9810.0 9827.5 9876.3 9907.8 3383.4 3497.6 3592.0 3606.3 3613.5 47900.3 47990.5 48589.0 49386.0 49593.3 9923.9 10018.8 10079.1 10158.4 10318.6 18037.3 18118.7 18142.2 18185.7 18191.3 7389.2 7435.1 7521.1 7653.5 7833.1 4273.2 4457.5 4536.0 4577.2 4600.5 2962.4 2988.2 2989.7 2992.5 2995.1 575.9 582.8 583.3 593.0 596.5 568.2 677.0 677.2 679.4 681.1 9159.2 9860.7 9991.9 10018.8 10052.1 663.2 691.2 691.4 693.3 717.7 4169.3 4392.8 4414.9 4470.4 4496.3 56865.7 61174.7 61474.4 61546.4 61666.1 53476.2 54023.5 54664.2 55193.9 55489.4 44526.8 45823.0 48095.0 48108.0 48805.0 8821.3 8834.5 9163.5 9212.0 9292.8 35044.5 35664.8 35902.8 36178.6 36249.5 40654.2 41254.8 43198.4 45276.3 45396.8 11560.1 11564.4 11651.3 11714.5 11774.6 12611.4 12664.1 12698.2 12761.8 12958.0 11819.4 12034.1 12137.6 12475.5 12476.7 11684.4 11898.4 12105.3 12134.6 12561.5 11427.0 11625.0 11633.8 12094.4 12111.3 9145.2 9158.9 9291.4 9590.9 9658.6 8878.5 9083.4 9088.4 9328.3 9679.8 06:05:21 INFO - 2015-11-24 06:05:21,949 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:05:21 INFO - 2015-11-24 06:05:21,949 DEBUG : BROWSER_OUTPUT: 06:05:22 INFO - 2015-11-24 06:05:22,605 INFO : Browser exited with error code: 0 06:05:22 INFO - 2015-11-24 06:05:22,629 INFO : Completed test dromaeo_css (00:18:30) 06:05:22 INFO - 2015-11-24 06:05:22,629 INFO : Starting test kraken 06:05:22 INFO - 2015-11-24 06:05:22,629 DEBUG : operating with platform_type : linux_ 06:05:22 INFO - 2015-11-24 06:05:22,629 INFO : Initialising browser for kraken test... 06:05:22 INFO - 2015-11-24 06:05:22,635 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpAjiwhX/profile http://localhost:53454/getInfo.html 06:05:26 INFO - 2015-11-24 06:05:26,960 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:05:26 INFO - 2015-11-24 06:05:26,960 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:05:26 INFO - 2015-11-24 06:05:26,960 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:05:26 INFO - 2015-11-24 06:05:26,960 DEBUG : BROWSER_OUTPUT: __metrics 06:05:27 INFO - 2015-11-24 06:05:27,945 INFO : Browser initialized. 06:05:27 INFO - 2015-11-24 06:05:27,946 INFO : Running cycle 1/1 for kraken test... 06:05:27 INFO - 2015-11-24 06:05:27,946 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpAjiwhX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:05:39 INFO - 2015-11-24 06:05:39,692 DEBUG : BROWSER_OUTPUT: RSS: Main: 140460032 06:05:39 INFO - 2015-11-24 06:05:39,692 DEBUG : BROWSER_OUTPUT: 06:05:42 INFO - 2015-11-24 06:05:42,168 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53454/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:42 INFO - 2015-11-24 06:05:42,774 DEBUG : BROWSER_OUTPUT: RSS: Main: 139890688 06:05:42 INFO - 2015-11-24 06:05:42,774 DEBUG : BROWSER_OUTPUT: 06:05:45 INFO - 2015-11-24 06:05:45,436 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53454/tests/kraken/driver.html?testName=audio-dft) 06:05:46 INFO - 2015-11-24 06:05:46,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 140496896 06:05:46 INFO - 2015-11-24 06:05:46,026 DEBUG : BROWSER_OUTPUT: 06:05:48 INFO - 2015-11-24 06:05:48,877 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53454/tests/kraken/driver.html?testName=audio-fft) 06:05:49 INFO - 2015-11-24 06:05:49,458 DEBUG : BROWSER_OUTPUT: RSS: Main: 136347648 06:05:49 INFO - 2015-11-24 06:05:49,459 DEBUG : BROWSER_OUTPUT: 06:05:51 INFO - 2015-11-24 06:05:51,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53454/tests/kraken/driver.html?testName=audio-oscillator) 06:05:52 INFO - 2015-11-24 06:05:52,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 136724480 06:05:52 INFO - 2015-11-24 06:05:52,037 DEBUG : BROWSER_OUTPUT: 06:05:54 INFO - 2015-11-24 06:05:54,230 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53454/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:54 INFO - 2015-11-24 06:05:54,811 DEBUG : BROWSER_OUTPUT: RSS: Main: 135073792 06:05:54 INFO - 2015-11-24 06:05:54,812 DEBUG : BROWSER_OUTPUT: 06:05:57 INFO - 2015-11-24 06:05:57,851 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53454/tests/kraken/driver.html?testName=imaging-darkroom) 06:05:58 INFO - 2015-11-24 06:05:58,445 DEBUG : BROWSER_OUTPUT: RSS: Main: 137252864 06:05:58 INFO - 2015-11-24 06:05:58,445 DEBUG : BROWSER_OUTPUT: 06:06:01 INFO - 2015-11-24 06:06:01,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53454/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:02 INFO - 2015-11-24 06:06:02,455 DEBUG : BROWSER_OUTPUT: RSS: Main: 137654272 06:06:02 INFO - 2015-11-24 06:06:02,455 DEBUG : BROWSER_OUTPUT: 06:06:05 INFO - 2015-11-24 06:06:05,293 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53454/tests/kraken/driver.html?testName=json-parse-financial) 06:06:05 INFO - 2015-11-24 06:06:05,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 137998336 06:06:05 INFO - 2015-11-24 06:06:05,879 DEBUG : BROWSER_OUTPUT: 06:06:07 INFO - 2015-11-24 06:06:07,448 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53454/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:08 INFO - 2015-11-24 06:06:08,021 DEBUG : BROWSER_OUTPUT: RSS: Main: 138739712 06:06:08 INFO - 2015-11-24 06:06:08,021 DEBUG : BROWSER_OUTPUT: 06:06:09 INFO - 2015-11-24 06:06:09,856 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:10 INFO - 2015-11-24 06:06:10,462 DEBUG : BROWSER_OUTPUT: RSS: Main: 137900032 06:06:10 INFO - 2015-11-24 06:06:10,462 DEBUG : BROWSER_OUTPUT: 06:06:12 INFO - 2015-11-24 06:06:12,559 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:13 INFO - 2015-11-24 06:06:13,146 DEBUG : BROWSER_OUTPUT: RSS: Main: 139333632 06:06:13 INFO - 2015-11-24 06:06:13,146 DEBUG : BROWSER_OUTPUT: 06:06:15 INFO - 2015-11-24 06:06:15,607 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:16 INFO - 2015-11-24 06:06:16,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 138698752 06:06:16 INFO - 2015-11-24 06:06:16,201 DEBUG : BROWSER_OUTPUT: 06:06:18 INFO - 2015-11-24 06:06:18,816 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:19 INFO - 2015-11-24 06:06:19,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 138244096 06:06:19 INFO - 2015-11-24 06:06:19,409 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,078 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53454/tests/kraken/driver.html?testName=ai-astar) 06:06:21 INFO - 2015-11-24 06:06:21,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 138108928 06:06:21 INFO - 2015-11-24 06:06:21,324 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,326 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:06:21 INFO - 2015-11-24 06:06:21,326 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:06:21 INFO - 2015-11-24 06:06:21,326 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:06:21 INFO - 2015-11-24 06:06:21,326 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:06:21 INFO - 2015-11-24 06:06:21,326 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;107;111;109;111;123;109;110;111;110 06:06:21 INFO - 2015-11-24 06:06:21,327 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;125;149;166;132;151;153;129;167;138 06:06:21 INFO - 2015-11-24 06:06:21,327 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;161;161;161;160;162;173;170;162 06:06:21 INFO - 2015-11-24 06:06:21,327 DEBUG : BROWSER_OUTPUT: |3;audio-fft;78;72;70;72;71;71;138;72;73;72 06:06:21 INFO - 2015-11-24 06:06:21,327 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;135;93;94;93;138;92;94;131 06:06:21 INFO - 2015-11-24 06:06:21,327 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;112;110;109;111;111;110;110;110;110 06:06:21 INFO - 2015-11-24 06:06:21,327 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;171;148;148;148;148;147;146;146 06:06:21 INFO - 2015-11-24 06:06:21,327 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;98;98;98;96;96;96;97;96 06:06:21 INFO - 2015-11-24 06:06:21,328 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;61;60;60;61;61;61;63;58;59 06:06:21 INFO - 2015-11-24 06:06:21,328 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;70;70;71;70;70;70;70;70 06:06:21 INFO - 2015-11-24 06:06:21,328 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;93;87;86;86;84;86;85;84;87 06:06:21 INFO - 2015-11-24 06:06:21,328 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;131;114;123;119;132;121;123;123;129;123 06:06:21 INFO - 2015-11-24 06:06:21,328 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;151;149;153;149;140;163;151;167;143 06:06:21 INFO - 2015-11-24 06:06:21,328 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;67;67;69;70;68;68;66;68;67 06:06:21 INFO - 2015-11-24 06:06:21,328 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:06:21 INFO - 2015-11-24 06:06:21,329 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:06:21 INFO - 2015-11-24 06:06:21,329 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,724 06:06:21 INFO - 2015-11-24 06:06:21,329 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:06:21 INFO - 2015-11-24 06:06:21,329 DEBUG : BROWSER_OUTPUT: __startTimestamp1448373981325__endTimestamp 06:06:21 INFO - 2015-11-24 06:06:21,329 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,332 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:4.35 (3.9%) stddev-sans-first:4.60 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: Values: 112.0 107.0 111.0 109.0 111.0 123.0 109.0 110.0 111.0 110.0 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.90 Median:150.00 stddev:14.62 (9.7%) stddev-sans-first:15.46 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: Values: 149.0 125.0 149.0 166.0 132.0 151.0 153.0 129.0 167.0 138.0 06:06:21 INFO - 2015-11-24 06:06:21,333 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.50 Median:162.00 stddev:4.35 (2.7%) stddev-sans-first:4.61 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 161.0 161.0 161.0 160.0 162.0 173.0 170.0 162.0 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.90 Median:72.00 stddev:20.88 (29.0%) stddev-sans-first:22.14 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: Values: 78.0 72.0 70.0 72.0 71.0 71.0 138.0 72.0 73.0 72.0 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:93.50 stddev:20.00 (21.4%) stddev-sans-first:20.75 06:06:21 INFO - 2015-11-24 06:06:21,334 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 135.0 93.0 94.0 93.0 138.0 92.0 94.0 131.0 06:06:21 INFO - 2015-11-24 06:06:21,335 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,335 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:110.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 06:06:21 INFO - 2015-11-24 06:06:21,335 DEBUG : BROWSER_OUTPUT: Values: 110.0 112.0 110.0 109.0 111.0 111.0 110.0 110.0 110.0 110.0 06:06:21 INFO - 2015-11-24 06:06:21,335 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,335 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:149.60 Median:148.00 stddev:7.56 (5.1%) stddev-sans-first:7.96 06:06:21 INFO - 2015-11-24 06:06:21,335 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 171.0 148.0 148.0 148.0 148.0 147.0 146.0 146.0 06:06:21 INFO - 2015-11-24 06:06:21,335 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.70 Median:96.50 stddev:0.95 (1.0%) stddev-sans-first:0.97 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 98.0 98.0 98.0 96.0 96.0 96.0 97.0 96.0 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:60.60 Median:61.00 stddev:1.43 (2.3%) stddev-sans-first:1.42 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: Values: 62.0 61.0 60.0 60.0 61.0 61.0 61.0 63.0 58.0 59.0 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 70.0 71.0 70.0 70.0 70.0 70.0 70.0 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,336 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.00 stddev:2.55 (3.0%) stddev-sans-first:2.70 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: Values: 86.0 93.0 87.0 86.0 86.0 84.0 86.0 85.0 84.0 87.0 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:123.00 stddev:5.53 (4.5%) stddev-sans-first:5.22 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: Values: 131.0 114.0 123.0 119.0 132.0 121.0 123.0 123.0 129.0 123.0 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:151.00 stddev:8.79 (5.8%) stddev-sans-first:8.60 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: Values: 141.0 151.0 149.0 153.0 149.0 140.0 163.0 151.0 167.0 143.0 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 06:06:21 INFO - 2015-11-24 06:06:21,337 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 67.0 69.0 70.0 68.0 68.0 66.0 68.0 67.0 06:06:21 INFO - 2015-11-24 06:06:21,338 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:06:21 INFO - 2015-11-24 06:06:21,338 DEBUG : BROWSER_OUTPUT: 06:06:22 INFO - 2015-11-24 06:06:22,169 INFO : Browser exited with error code: 0 06:06:22 INFO - 2015-11-24 06:06:22,220 INFO : Completed test kraken (00:00:59) 06:06:22 INFO - 2015-11-24 06:06:22,220 INFO : Starting test v8_7 06:06:22 INFO - 2015-11-24 06:06:22,220 DEBUG : operating with platform_type : linux_ 06:06:22 INFO - 2015-11-24 06:06:22,220 INFO : Initialising browser for v8_7 test... 06:06:22 INFO - 2015-11-24 06:06:22,226 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfyQ1PO/profile http://localhost:53454/getInfo.html 06:06:26 INFO - 2015-11-24 06:06:26,532 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:06:26 INFO - 2015-11-24 06:06:26,532 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:06:26 INFO - 2015-11-24 06:06:26,532 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:06:26 INFO - 2015-11-24 06:06:26,532 DEBUG : BROWSER_OUTPUT: __metrics 06:06:27 INFO - 2015-11-24 06:06:27,509 INFO : Browser initialized. 06:06:27 INFO - 2015-11-24 06:06:27,510 INFO : Running cycle 1/1 for v8_7 test... 06:06:27 INFO - 2015-11-24 06:06:27,510 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfyQ1PO/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:39 INFO - 2015-11-24 06:06:39,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 138817536 06:06:39 INFO - 2015-11-24 06:06:39,289 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,678 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53454/tests/v8_7/run.html (next: http://localhost:53454/tests/v8_7/run.html) 06:07:00 INFO - 2015-11-24 06:07:00,928 DEBUG : BROWSER_OUTPUT: RSS: Main: 137728000 06:07:00 INFO - 2015-11-24 06:07:00,928 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,928 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: |0;Richards;165.18004625041294 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.1850679575498 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.79854504756577 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5208.333333333333 06:07:00 INFO - 2015-11-24 06:07:00,929 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1069.51871657754 06:07:00 INFO - 2015-11-24 06:07:00,930 DEBUG : BROWSER_OUTPUT: |5;Earley;702.7406886858749 06:07:00 INFO - 2015-11-24 06:07:00,930 DEBUG : BROWSER_OUTPUT: |6;Boyer;11940.47619047619 06:07:00 INFO - 2015-11-24 06:07:00,930 DEBUG : BROWSER_OUTPUT: |7;RegExp;24829.268292682926 06:07:00 INFO - 2015-11-24 06:07:00,930 DEBUG : BROWSER_OUTPUT: |8;Splay;580.2540415704387 06:07:00 INFO - 2015-11-24 06:07:00,930 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 06:07:00 INFO - 2015-11-24 06:07:00,931 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:07:00 INFO - 2015-11-24 06:07:00,931 DEBUG : BROWSER_OUTPUT: __startTimestamp1448374020928__endTimestamp 06:07:00 INFO - 2015-11-24 06:07:00,931 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,931 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:07:00 INFO - 2015-11-24 06:07:00,931 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:07:00 INFO - 2015-11-24 06:07:00,931 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,932 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.18 Median:165.18 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,932 DEBUG : BROWSER_OUTPUT: Values: 165.2 06:07:00 INFO - 2015-11-24 06:07:00,932 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,932 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.19 Median:186.19 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,932 DEBUG : BROWSER_OUTPUT: Values: 186.2 06:07:00 INFO - 2015-11-24 06:07:00,933 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,933 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.80 Median:279.80 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,933 DEBUG : BROWSER_OUTPUT: Values: 279.8 06:07:00 INFO - 2015-11-24 06:07:00,933 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,933 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,933 DEBUG : BROWSER_OUTPUT: Values: 5208.3 06:07:00 INFO - 2015-11-24 06:07:00,934 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,934 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1069.52 Median:1069.52 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,934 DEBUG : BROWSER_OUTPUT: Values: 1069.5 06:07:00 INFO - 2015-11-24 06:07:00,934 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,934 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:702.74 Median:702.74 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,935 DEBUG : BROWSER_OUTPUT: Values: 702.7 06:07:00 INFO - 2015-11-24 06:07:00,935 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,935 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11940.48 Median:11940.48 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,935 DEBUG : BROWSER_OUTPUT: Values: 11940.5 06:07:00 INFO - 2015-11-24 06:07:00,935 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,937 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24829.27 Median:24829.27 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,937 DEBUG : BROWSER_OUTPUT: Values: 24829.3 06:07:00 INFO - 2015-11-24 06:07:00,937 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,937 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:580.25 Median:580.25 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,937 DEBUG : BROWSER_OUTPUT: Values: 580.3 06:07:00 INFO - 2015-11-24 06:07:00,937 DEBUG : BROWSER_OUTPUT: 06:07:00 INFO - 2015-11-24 06:07:00,938 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 06:07:00 INFO - 2015-11-24 06:07:00,938 DEBUG : BROWSER_OUTPUT: Values: 4835.7 06:07:00 INFO - 2015-11-24 06:07:00,938 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:07:00 INFO - 2015-11-24 06:07:00,938 DEBUG : BROWSER_OUTPUT: 06:07:01 INFO - 2015-11-24 06:07:01,797 INFO : Browser exited with error code: 0 06:07:01 INFO - 2015-11-24 06:07:01,809 INFO : Completed test v8_7 (00:00:39) 06:07:01 INFO - 2015-11-24 06:07:01,953 INFO : Completed test suite (00:20:09) 06:07:01 INFO - 2015-11-24 06:07:01,954 DEBUG : Working with test: dromaeo_css 06:07:01 INFO - 2015-11-24 06:07:01,954 DEBUG : Generating results file: dromaeo_css 06:07:01 INFO - 2015-11-24 06:07:01,955 DEBUG : Working with test: kraken 06:07:01 INFO - 2015-11-24 06:07:01,956 DEBUG : Generating results file: kraken 06:07:01 INFO - 2015-11-24 06:07:01,956 INFO : javascript benchmark 06:07:01 INFO - 2015-11-24 06:07:01,956 DEBUG : Working with test: v8_7 06:07:01 INFO - 2015-11-24 06:07:01,956 DEBUG : Generating results file: v8_7 06:07:01 INFO - 2015-11-24 06:07:01,957 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:02 INFO - 2015-11-24 06:07:02,026 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 06:07:02 INFO - 2015-11-24 06:07:02,027 DEBUG : process_Request line: dromaeo_css 7016.82 graph.html#tests=[[72,63,43]] 06:07:02 INFO - 2015-11-24 06:07:02,027 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:02 INFO - 2015-11-24 06:07:02,061 DEBUG : process_Request line: kraken 1521.30 graph.html#tests=[[232,63,43]] 06:07:02 INFO - 2015-11-24 06:07:02,061 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:02 INFO - 2015-11-24 06:07:02,093 DEBUG : process_Request line: v8_7 21611.50 graph.html#tests=[[230,63,43]] 06:07:02 INFO - 2015-11-24 06:07:02,098 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3208.1498402923226, 3209.4383482890503, 3259.462188738858, 3261.6575259597744, 3282.179455136216, 5276.551493123079, 5484.890302193954, 5494.120940649497, 5507.9449205507935, 5513.421090785465, 5490.286262785842, 5491.039068163065, 5509.669419834802, 5610.046292130338, 5617.045102332603, 4942.431620363668, 5008.924866127011, 5077.33994580704, 5093.414257360414, 5099.6685215460875, 5019.924701129486, 5088.669236499634, 5133.101707201225, 5185.481451854803, 5191.688498690071, 4880.170371036932, 5022.573081288095, 5080.79676812927, 5109.591232701394, 5226.947730522703, 4962.057209130254, 5057.721825299607, 5063.215201643751, 5071.46749591295, 5075.720835354054, 5031.572316353115, 5057.721825299607, 5084.3898732152375, 5085.974570127163, 5112.744362781883, 5107.004134193834, 5220.425753167148, 5279.1289437243195, 5348.0, 5411.026015317241, 5953.702314884238, 5957.970210148965, 5961.791337303217, 5967.6419414835245, 6013.939860601382, 6004.699765011732, 6009.789657362015, 6015.218021657157, 6015.969920150371, 6029.909551356734, 5343.225232341328, 5436.7553460094, 5453.345598528202, 5569.526590239875, 5594.9720251398485, 6089.238845144357, 6109.450149486567, 6116.327204007555, 6117.174181485531, 6137.1101190328045, 5930.347661757203, 5935.881282374328, 5949.464548190597, 5952.077427998667, 5954.970225148846, 5783.508401785895, 5790.652560846363, 5817.825465236049, 5867.471927526543, 5887.0, 5903.468687818117, 5941.049432090931, 5974.970125149346, 5978.342382338026, 6017.759289628453, 5962.0, 5968.104784282393, 5985.132155837379, 5995.490383317467, 5999.820005399845, 4405.823767049346, 4508.07584445188, 4530.750808705487, 4593.012502312019, 4620.630349572026, 4053.0880551875593, 4081.142959978438, 4083.547057590818, 4117.464729585195, 4118.299889018874, 5694.601377903507, 5722.227499287626, 5726.856828579319, 5738.254026976467, 5759.913601295984, 5717.342505611825, 5884.470397664145, 5900.881982360329, 5984.042553191469, 6049.334573196945, 5717.171010203498, 5739.827805165852, 5747.741351639186, 5772.509336706343, 5780.913286300709, 5226.660267082628, 5228.712420816892, 5237.869053273698, 5260.184671375943, 5282.260483532308, 5904.763809447574, 6047.15339852421, 6105.938940610626, 6217.378262173746, 6226.221722284805, 5949.583529152917, 5960.940390596039, 5962.761489540369, 5976.820695379146, 6018.7291571878495, 6126.938730612816, 6163.0, 6173.969130154321, 6185.072239164161, 6196.318404975539, 5735.799247026334, 5805.622634528742, 5837.066069428871, 5882.852928676817, 5917.5857689961285, 5940.138679891305, 5940.702964851654, 5992.370801065826, 6002.579819412599, 6009.0, 5591.860203494945, 5783.363829978614, 5824.359320474659, 5830.766769329349, 5845.152452894392, 5663.801766938137, 5711.657300561979, 5767.9134812977, 5791.276090488689, 5850.853728656647, 5701.942980570141, 5724.914126288026, 5737.397573254915, 5744.971275143597, 5759.971200143972, 5686.4029276926985, 5739.253896993531, 5800.796972105956, 5831.79588714393, 5852.707364631837, 5757.942420575908, 5790.305163380422, 5827.271591051119, 5827.970860145672, 5843.737031833492], "unit": "ms", "name": "dojo.html", "value": 5483.718516754384}, {"lowerIsBetter": false, "replicates": [541.2666429615582, 592.2389729197981, 595.6604735300879, 601.3445344574415, 605.6124080588421, 36493.08767280819, 36734.265314693694, 37107.628923710756, 37150.44274335887, 37153.81423092884, 40965.795171024176, 41581.96045098874, 41596.58403415965, 41650.0, 41744.58255417445, 8649.783755406103, 8689.7827554311, 8776.780580485507, 8803.95598022009, 8835.337349698766, 8580.313574913991, 8669.653213871437, 8682.218600325969, 8685.218330350268, 8695.652173913037, 8696.17386348296, 8728.039915609277, 8779.473231606093, 8803.73588792334, 8812.339074569401, 8674.913250867474, 8748.08145144757, 8752.387332886732, 8755.868661970075, 8760.649574017032, 8602.053774084845, 8731.126887311219, 8738.213560779497, 8754.73735787921, 8757.2994160467, 38790.80604596987, 39104.413433798516, 39348.60651393478, 39362.60637393618, 39416.408753868716, 3292.1275861896615, 3293.917652058694, 3301.1747063234193, 3610.4584312353095, 3985.8422564898037, 29276.0, 29710.40579188426, 29935.70064299351, 30009.549856752164, 30088.097357079103, 3782.7919464429447, 3938.0548668319684, 3990.142119444304, 4000.4799376080923, 4004.4193591929006, 264599.0, 266993.0, 267381.0, 267463.0, 267791.9999999981, 50811.0, 52224.738876305375, 56829.0, 57175.714121429955, 58101.4189858096, 59222.40777592169, 59507.10739338913, 59534.40465595375, 59769.0, 59865.80268394608, 14507.92746036263, 14666.120032798066, 14716.969812113264, 14735.26323683833, 14739.557813265405, 10849.0, 10857.619983300547, 10887.183461240438, 10891.292066015683, 10899.673009809718, 18490.815091849177, 18621.44135675932, 18642.53393665142, 18694.0, 18757.624847502975, 7505.962470187614, 7705.807354816175, 7788.727394541163, 7809.609519524001, 7928.3260922821055, 947.8625599288065, 989.7080361293292, 1032.9741756455999, 1078.369154044877, 1080.2168427889717, 28024.579631305547, 28852.134435966953, 28900.132996010154, 28956.42087158245, 29211.415771684446, 1059.8569193158978, 1087.429099722636, 1143.2340331977596, 1157.0280963990288, 1200.6278053803348, 14919.104853708595, 14929.776053359208, 15134.924325378302, 15192.0, 15374.538763837103, 313131.0, 318061.0, 318659.0, 319517.0, 319659.0, 50993.49006509961, 51025.48974510207, 51151.48848511542, 51279.0, 51393.486065139616, 1317.6639956810952, 1325.3307079924577, 1340.0619566303626, 1348.2315080404076, 1350.3315858649835, 27148.32129196786, 27688.30779230455, 27994.44011119807, 28382.716172838835, 28821.135365939055, 43359.783201083796, 43504.564954350055, 43576.34635480407, 43792.0, 43845.0, 208504.0, 208886.0, 210411.0, 210615.0, 210848.0, 29316.7068329314, 31841.203969900936, 31948.52077218797, 32193.51709724403, 32275.0, 38062.0, 38659.2268154641, 39048.0, 39099.218015640094, 39187.216255675296, 34393.828030860685, 34813.303733925684, 34815.47776783401, 35259.0, 35365.29269414649, 33818.46361522945, 35101.26132972118, 35552.46671299984, 35761.64238357583, 35968.460473092404, 32684.836575816968, 33092.83453582717, 33252.16869578183, 33257.0, 33594.49608255829, 31087.84456077705, 31962.36075278528, 32409.679414287446, 32778.344433111684, 32886.34227315488, 24747.87626061858, 25003.12489062874, 25030.123945661813, 25271.87364063168, 25417.87291063607], "unit": "ms", "name": "ext.html", "value": 18042.636938154723}, {"lowerIsBetter": false, "replicates": [349.6486031538304, 350.21901160412295, 351.95776506819186, 352.1548284118117, 356.2198784661591, 16501.257443415045, 17163.656726865454, 17894.194761235743, 17899.642007159848, 18521.536961575966, 11292.774144517125, 11330.320180789158, 11347.659570212907, 11889.286642801439, 16429.342826286935, 4892.339534162887, 4893.119238537062, 4959.801607935697, 4988.575971042465, 5027.673201241926, 4924.70451772893, 4931.457539670633, 4933.629977751683, 4949.752512374385, 4959.826406075788, 3705.147816002321, 4954.801807927679, 4975.800967961295, 5548.55611551075, 5946.048632218846, 5868.853278668024, 5909.852253693649, 5923.911141332862, 5924.970375148118, 5926.822195334125, 4974.0, 5092.414372347191, 5135.204043373283, 5150.356205474316, 5289.312389389354, 12949.741005179938, 13097.279649619362, 19083.809161908343, 19856.900715496475, 20622.0, 2115.608612406694, 2193.100828660242, 2272.4659704969317, 2306.342692332677, 2309.0648287443446, 9510.904890951071, 9615.278854085902, 9981.600735970553, 9997.400155990665, 12290.446929888176, 1845.5570663040908, 1903.7715474143054, 1941.9126139323623, 1942.1260432805293, 1960.3334866145578, 61907.0, 64939.99999999953, 65110.674446627934, 66334.66832665855, 66874.33125668729, 10041.246906482043, 10326.380417174843, 10360.533775980099, 10431.21765867563, 10518.947405262925, 13530.120542164728, 13549.0, 13618.387172577257, 13656.522021729192, 13664.93167534156, 7471.3649339806725, 7484.812879678052, 7639.885401718979, 7695.653695583712, 7729.613519324012, 7311.488195826348, 7339.229380915034, 7360.33756961876, 7361.411087113018, 7397.18630950595, 4402.0, 4434.534373890695, 4444.799984000663, 4485.708428952173, 4486.1252055849445, 1775.5738622730453, 1863.1615772902248, 1869.5980364221757, 1884.4911873794, 1907.9141438635017, 431.7733190075236, 433.8112920879428, 437.78767297860514, 467.7731300319342, 889.208604342136, 10667.253292269466, 10717.678469645924, 10774.0, 10780.191485638608, 10996.890031099587, 451.62740738890545, 457.636179237507, 457.8695071904491, 459.10932790386545, 483.2606112647655, 5861.41385861419, 5950.851228719317, 5972.163897054415, 5995.160677505153, 6027.427394397525, 557401.0, 564438.0, 565489.0, 566016.0, 566278.0, 82458.58770706228, 85324.0, 86387.0, 86509.56745216233, 86870.56564717263, 1163.4880652512868, 1164.7204670879098, 1176.9058475321954, 1192.9821052684217, 1197.0961923747689, 15900.204989750697, 15901.681966360838, 15931.8406815935, 15932.761008584648, 16439.424620138238, 33879.830600847825, 34430.82784586061, 35169.1207719809, 35271.294574108884, 35564.644353556134, 46874.0, 46995.76502117467, 47596.7620161897, 48448.757756210995, 52025.0, 9725.270604704534, 13324.733505329645, 13486.797698034734, 13599.388027538587, 13782.517611883535, 18435.907820460812, 18763.718544221574, 18828.341008064654, 19338.4198474046, 19404.611907761482, 12709.682257943625, 15480.303386347416, 15765.921170394075, 15896.76154857655, 15975.68048639044, 12703.301318427206, 14345.282735863373, 14347.56957291283, 15177.392904283704, 15718.0, 15590.9220453897, 15701.057936523845, 16050.117243551716, 16423.096729679983, 16440.260188291795, 14828.85171148318, 15078.24608769579, 15082.924585377441, 15193.696126077195, 15227.16250606183, 15574.377024918876, 15686.294116765, 15706.842931570538, 16121.435749748702, 16143.354265829235], "unit": "ms", "name": "jquery.html", "value": 8610.962062679499}, {"lowerIsBetter": false, "replicates": [1437.971240575188, 1713.3061110250349, 1758.9296428142875, 1765.6115654555992, 1786.0980204996479, 7225.169105552863, 7362.5950572718475, 7630.084389873209, 7895.921040789591, 7905.209479052099, 7631.503952243101, 7657.4639775215755, 7702.152763196044, 7721.613919304026, 7731.884021739665, 2256.7291924968986, 2285.9771402286015, 2287.2795069553035, 2299.758130609469, 2302.021640802652, 2307.849989750669, 2323.024329781496, 2343.5664402085586, 2369.6327069304198, 2371.632396978471, 2111.7465904091455, 2154.3213887625348, 2164.729408823897, 2264.1283106004225, 2301.1945818963395, 2294.8737819419925, 2308.2613563659634, 2352.811775058, 2353.635186546088, 2354.540864531417, 2286.085565773687, 2375.0737212487143, 2375.121205154087, 2381.618940969454, 2387.937367871298, 7702.9229707703325, 7729.227077292282, 7731.188225236382, 7762.45662803604, 7776.65005074773, 4742.478327383985, 4802.495737947535, 4823.565879070866, 4847.903041939141, 4848.248521479177, 3154.5899033125547, 3160.225744692542, 3249.171461277359, 3270.8201048942296, 3283.5074738789135, 978.2467500024941, 991.3011327014437, 992.0525897767615, 1004.6684594083935, 1012.1700205831221, 10419.0, 10492.003259690317, 10506.632267870664, 10511.94744026275, 10532.578696852193, 4200.306949353384, 4259.254630439661, 4289.806958686867, 4331.501877284152, 4348.586884245991, 8590.91409085901, 8591.269742071869, 8634.78413039679, 8636.265917397091, 8650.35122365825, 4726.763661816895, 4751.263554149113, 4751.477337492873, 4768.213244814636, 4771.021940502189, 4322.15717935006, 4342.370356298382, 4349.956500435019, 4358.259095953696, 4362.345648152739, 3505.158761897126, 3528.347255757693, 3537.0, 3542.929141417157, 3552.8934131976084, 1084.5553323137476, 1097.9341239525654, 1102.239454776202, 1111.3054341036852, 1120.2830188679252, 952.1288021460286, 1004.829179039565, 1071.373246650718, 1192.4059948484892, 1343.5431953135785, 7005.894911576331, 7154.35610795031, 7155.320244576861, 7172.0, 7181.748638797617, 619.2847261413075, 620.2804746494045, 630.9968450157812, 631.1826185090227, 768.588804989328, 5531.280933478622, 5608.383077861432, 5710.65736055838, 5732.140178973104, 5768.0, 9065.138811812867, 9228.815423691489, 9305.162535371848, 9311.674091406769, 9327.253819694408, 8911.955440222888, 9185.632574696938, 9187.356885017985, 9215.49314787693, 9263.722088337227, 7117.501774875709, 7180.353768160786, 7186.209516953131, 7202.819929501804, 7271.454640901953, 6892.276310987274, 6929.792106236821, 6975.128108986377, 6994.6852391643515, 6997.26528714498, 7971.242731940456, 8042.195780421911, 8142.633581488965, 8228.465149765247, 8277.917220827956, 8241.587920604066, 8251.0, 8332.458390204618, 8437.071922088688, 8492.74521764348, 6855.108835851307, 6891.4486841053595, 6928.861422771617, 6935.895961560682, 6941.861162776615, 7391.741289054858, 7450.739224127129, 7461.962690186731, 7475.88786168197, 7477.439192060726, 7019.894701579583, 7053.576785392687, 7078.681459334445, 7215.314545118205, 7247.094113235846, 6482.189726284215, 6685.498587605852, 6746.628935408599, 6908.067410899665, 7115.039469671735, 7004.474664400293, 7020.7893763187185, 7025.754098606524, 7043.119610048744, 7049.154101507852, 6370.203724534434, 6529.869402611826, 6533.542652014314, 6546.738130474728, 6548.6070835748105, 6398.552101353047, 6426.518011149089, 6468.838279043061, 6502.284748677547, 6505.967470162808], "unit": "ms", "name": "mootools.html", "value": 4403.164336923758}, {"lowerIsBetter": false, "replicates": [335.2122512096573, 344.1173390253998, 344.8706734974385, 345.9654034596539, 353.5845381676531, 6169.0, 6216.129741836146, 6219.284782250043, 6307.180066591337, 6432.42108210261, 6214.03682429223, 6249.468795152417, 6254.43710066094, 6258.906116408258, 6265.624062556239, 4708.929366059512, 4728.787204575802, 4742.288656701497, 4745.549172828576, 4750.406199225097, 4731.668783185179, 4746.715197088169, 4750.4299484061785, 4760.047990401916, 4761.095391875532, 4601.907961840761, 4619.099275641252, 4658.883527911812, 4687.929681054787, 4692.5072867348945, 4631.652626053059, 4646.372739680134, 4663.393758811367, 4671.205894997858, 4671.9955209629925, 4003.4795476588147, 4031.8992025199314, 4035.7982100895133, 4055.452513910614, 4069.409935559327, 5808.506276966463, 5819.592628516006, 5840.269966254218, 5840.591158618856, 5852.912206316909, 5320.5211530962015, 5437.510624043815, 5437.510624043815, 5483.259759932398, 5489.1217405214975, 6561.639109848957, 6567.244766851778, 6568.901466478007, 6575.11235983141, 6575.276719560845, 4490.281554951192, 4491.079328737601, 4501.212287849646, 4501.7524036178, 4539.614132798716, 6759.290274521154, 6779.15260592426, 6825.965870170618, 6868.0, 6875.209350924658, 4574.6569007323915, 4610.654200934943, 4618.838340658061, 4623.468301145344, 4624.05206932578, 4649.3026046093355, 4665.510121437268, 4685.812567497262, 4689.132510485571, 4691.038337140878, 5394.64934779246, 5406.459354064641, 5409.188621706697, 5413.052715774724, 5415.837524874181, 6476.996065609838, 6600.702968366339, 6608.438282745924, 6626.536142470077, 6651.73393064272, 1636.2309714434198, 1659.8506134447716, 1660.9750853737203, 1664.6504234110973, 1667.0, 1097.863711059066, 1099.1920938110482, 1108.3128460354633, 1113.465536542448, 1118.3122379736403, 6383.553151279459, 6403.551751377452, 6407.871842563122, 6411.134496842867, 6427.260865000491, 6739.056532085512, 6743.5953842769595, 6746.0, 6757.966210169016, 6803.65981700913, 6248.375162483715, 6378.904316435257, 6381.489480841615, 6416.711247993758, 6447.645379504079, 3650.3976843821, 3700.3339398907915, 3715.7213209009433, 3720.088578298334, 3734.4958430612064, 7157.069580954442, 7157.57054576727, 7179.676914538858, 7190.352868241884, 7196.028536147658, 6888.827779305558, 7052.294770522906, 7060.7881763547175, 7062.0, 7091.503594748318, 7159.319864612853, 7160.283971602902, 7179.2820717928835, 7190.496665233383, 7197.136343638692, 6796.286389929184, 6839.760608378882, 6840.700266949264, 6846.0, 6857.074294970115, 6607.636579988147, 6784.694688739118, 6785.185777706707, 6789.0, 6793.422559082534, 7127.322904324081, 7143.178534468602, 7152.463565232524, 7161.176464706417, 7184.1019872515935, 4657.81225787422, 4741.336212930261, 4743.051389722138, 4749.857504274877, 4769.928451073168, 6649.537101837588, 6734.6632668367365, 6743.629100399329, 6745.0, 6773.288804675635, 6562.475001999924, 6563.638999855054, 6567.784959782455, 6568.540202185801, 6594.538382313192, 6445.903311450238, 6558.245801732862, 6573.868522629617, 6579.276279609335, 6579.407853293226, 6429.357064293533, 6431.774887878902, 6433.453156481564, 6434.195725534309, 6436.292007879223, 6548.967255163694, 6573.5398522103, 6605.702743376463, 6617.569857959217, 6619.0, 6340.873182536416, 6440.967795161182, 6447.9355206447335, 6451.483881289391, 6454.580452270588], "unit": "ms", "name": "prototype.html", "value": 4961.131911809206}, {"lowerIsBetter": false, "replicates": [715.3132992327365, 761.6534476813049, 816.6610856494555, 825.1830687619258, 849.3757088539926, 13778.311084445775, 14550.927245363784, 14590.197539135343, 14597.270136493173, 14627.414903403864, 12868.67828304293, 12902.548410805624, 12920.612381628544, 13511.391987360568, 13662.521811736591, 805.4844899264475, 814.7596459044596, 824.3116997307226, 824.4599787139404, 826.1944604011101, 804.3967024731452, 815.0789607743271, 815.9428839981204, 816.3673153306175, 820.0733171516179, 859.2867919626694, 862.9784255393603, 866.6143566113083, 867.8828358171629, 873.698573991975, 860.8321377331391, 863.1196179896501, 863.386995233385, 864.0193380513163, 868.7220089571372, 628.7485005997637, 630.6531407725732, 631.8104568629393, 631.9557630965834, 632.4371309534517, 12079.758404831942, 12176.695582610506, 12229.715879832667, 12256.325902075383, 12299.938500307531, 10872.402017889013, 10965.287256328393, 11059.115270778319, 11100.61147859821, 11242.550297988071, 9772.95113524435, 9809.950950245204, 9827.459489728062, 9876.259280553988, 9907.752306192331, 3383.3571621391857, 3497.562804649419, 3591.964080359189, 3606.2607165531003, 3613.49411082446, 47900.28149577759, 47990.5200947993, 48589.0, 49386.0, 49593.25610115815, 9923.900760992297, 10018.799624007479, 10079.143272821892, 10158.39049657023, 10318.587256509805, 18037.278508859497, 18118.728219076726, 18142.183601737688, 18185.7272140918, 18191.27234910615, 7389.187189409161, 7435.1449583297535, 7521.0974683038385, 7653.502522336031, 7833.099193592696, 4273.18809426208, 4457.465104187453, 4535.9773201133785, 4577.244754615518, 4600.539946005372, 2962.4223276460884, 2988.237999310162, 2989.6845388029196, 2992.5062364710016, 2995.1164406500134, 575.8646718021262, 582.8076734677546, 583.3000399520507, 592.9940700593025, 596.5257620191958, 568.156287912458, 676.9661516924134, 677.1975209376905, 679.3987321220756, 681.1383599746325, 9159.221466175317, 9860.654877079267, 9991.927615972518, 10018.82004757153, 10052.095311422006, 663.1677245057422, 691.2292793535172, 691.3639451704444, 693.2686016252801, 717.6519388096768, 4169.332906734908, 4392.846250381222, 4414.889627759268, 4470.351798989193, 4496.258117410591, 56865.71567142221, 61174.69412652908, 61474.38525614776, 61546.384536154066, 61666.075008874905, 53476.19785703217, 54023.45976540263, 54664.18003729947, 55193.896122077334, 55489.44510554923, 44526.77736611296, 45823.0, 48095.0, 48108.0, 48805.0, 8821.250193733604, 8834.51410172421, 9163.450192988443, 9212.0, 9292.767680808034, 35044.47433288554, 35664.82167589146, 35902.820485897406, 36178.63821361752, 36249.45625815668, 40654.1869162621, 41254.79372603118, 43198.35202472028, 45276.32085518654, 45396.773016134706, 11560.13299002595, 11564.363959982282, 11651.30092194471, 11714.531418743154, 11774.587889424172, 12611.369431528203, 12664.113512054068, 12698.174618649758, 12761.808572871229, 12958.0, 11819.40902954866, 12034.097442692007, 12137.635870923887, 12475.500979961063, 12476.688082798002, 11684.357360345262, 11898.398388415506, 12105.334206618369, 12134.635960921187, 12561.497540098293, 11427.0, 11625.0, 11633.76732465329, 12094.395280235776, 12111.273323600612, 9145.222656074307, 9158.862617060882, 9291.396059256127, 9590.904090959, 9658.613655453984, 8878.467291962503, 9083.355308658136, 9088.363814532919, 9328.347015708836, 9679.806403871742], "unit": "ms", "name": "yui.html", "value": 6412.569178999954}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 107.0, 111.0, 109.0, 111.0, 123.0, 109.0, 110.0, 111.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [149.0, 125.0, 149.0, 166.0, 132.0, 151.0, 153.0, 129.0, 167.0, 138.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.9}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 161.0, 161.0, 161.0, 160.0, 162.0, 173.0, 170.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 163.5}, {"lowerIsBetter": true, "replicates": [78.0, 72.0, 70.0, 72.0, 71.0, 71.0, 138.0, 72.0, 73.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 78.9}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 135.0, 93.0, 94.0, 93.0, 138.0, 92.0, 94.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 112.0, 110.0, 109.0, 111.0, 111.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.3}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 171.0, 148.0, 148.0, 148.0, 148.0, 147.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 149.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 98.0, 98.0, 98.0, 96.0, 96.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.7}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0, 63.0, 58.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 60.6}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 70.0, 70.0, 71.0, 70.0, 70.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.0}, {"lowerIsBetter": true, "replicates": [86.0, 93.0, 87.0, 86.0, 86.0, 84.0, 86.0, 85.0, 84.0, 87.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.4}, {"lowerIsBetter": true, "replicates": [131.0, 114.0, 123.0, 119.0, 132.0, 121.0, 123.0, 123.0, 129.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.8}, {"lowerIsBetter": true, "replicates": [141.0, 151.0, 149.0, 153.0, 149.0, 140.0, 163.0, 151.0, 167.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 150.7}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 67.0, 69.0, 70.0, 68.0, 68.0, 66.0, 68.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.8}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.18004625041294], "unit": "score", "name": "Richards", "value": 213.71830799999998}, {"lowerIsBetter": false, "replicates": [186.1850679575498], "unit": "score", "name": "DeltaBlue", "value": 355.1197780000001}, {"lowerIsBetter": false, "replicates": [279.79854504756577], "unit": "score", "name": "Encrypt", "value": 951.330894}, {"lowerIsBetter": false, "replicates": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [1069.51871657754], "unit": "score", "name": "RayTrace", "value": 691.889715}, {"lowerIsBetter": false, "replicates": [702.7406886858749], "unit": "score", "name": "Earley", "value": 948.3768489999995}, {"lowerIsBetter": false, "replicates": [11940.47619047619], "unit": "score", "name": "Boyer", "value": 55.815445663010976}, {"lowerIsBetter": false, "replicates": [24829.268292682926], "unit": "score", "name": "RegExp", "value": 36.68996561886052}, {"lowerIsBetter": false, "replicates": [580.2540415704387], "unit": "score", "name": "Splay", "value": 140.4402109452736}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7"}]} 06:07:02 INFO - RETURN: dromaeo_css: 7016.82 06:07:02 INFO - RETURN: kraken: 1521.30 06:07:02 INFO - RETURN: v8_7: 21611.50 06:07:02 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "21611.50"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1521.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "7016.82"}}} 06:07:02 INFO - Return code: 0 06:07:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:07:02 INFO - # TBPL SUCCESS # 06:07:02 INFO - Running post-action listener: _resource_record_post_action 06:07:02 INFO - Running post-run listener: _resource_record_post_run 06:07:03 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 28672; Write bytes: 522276864; Read time: 100; Write time: 686036 06:07:03 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 241451008; Read time: 0; Write time: 410448 06:07:03 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 8192; Write bytes: 274382848; Read time: 28; Write time: 274612 06:07:03 INFO - Running post-run listener: _upload_blobber_files 06:07:03 INFO - Blob upload gear active. 06:07:03 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:07:03 INFO - Copying logs to upload dir... 06:07:03 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.468457 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2015-11-24 06:07:02.789641) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:02.793886) ========= 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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 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.010000 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.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:02.849394) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:02.849754) ========= 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=b00de3c228505fac2445e70d000001bb-1448370323.220750-1021561196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 06:07:02.899296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 06:07:02.899648) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 06:07:02.899965) ========= ========= Total master_lag: 0.37 =========