builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1453799747.27 results: success (0) buildid: 20160125070233 builduid: eb2ea0af6ac2494ca810680a4890723f revision: 3bc504ea394f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.274875) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.275410) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.275784) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004187 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.293882) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.294297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.307786) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.308219) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.357821) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.358170) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.358597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.358913) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-26 01:15:48-- 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.1M=0s 2016-01-26 01:15:48 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139023 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.510986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.511369) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022276 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.561501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:47.561899) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3bc504ea394f --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3bc504ea394f --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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-26 01:15:48,649 Setting DEBUG logging. 2016-01-26 01:15:48,649 attempt 1/10 2016-01-26 01:15:48,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3bc504ea394f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-26 01:15:48,922 unpacking tar archive at: mozilla-beta-3bc504ea394f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.553275 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:48.127939) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:48.128359) ========= echo 3bc504ea394f in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'3bc504ea394f'] 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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 3bc504ea394f program finished with exit code 0 elapsedTime=0.004158 script_repo_revision: '3bc504ea394f' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:48.179912) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:48.180283) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 01:15:48.191260) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-01-26 01:15:48.191714) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:15:49 INFO - MultiFileLogger online at 20160126 01:15:49 in /builds/slave/test-pgo 01:15:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:15:49 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 01:15:49 INFO - {'append_to_log': False, 01:15:49 INFO - 'base_work_dir': '/builds/slave/test-pgo', 01:15:49 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:15:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 01:15:49 INFO - 'branch': 'Mozilla-Beta', 01:15:49 INFO - 'buildbot_json_path': 'buildprops.json', 01:15:49 INFO - 'config_files': ('talos/linux_config.py',), 01:15:49 INFO - 'default_actions': ('clobber', 01:15:49 INFO - 'read-buildbot-config', 01:15:49 INFO - 'download-and-extract', 01:15:49 INFO - 'populate-webroot', 01:15:49 INFO - 'create-virtualenv', 01:15:49 INFO - 'install', 01:15:49 INFO - 'run-tests'), 01:15:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:15:49 INFO - 'download_minidump_stackwalk': True, 01:15:49 INFO - 'download_symbols': 'ondemand', 01:15:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:15:49 INFO - 'tooltool.py': '/tools/tooltool.py', 01:15:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:15:49 INFO - '/tools/misc-python/virtualenv.py')}, 01:15:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:15:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:15:49 INFO - 'installer_path': 'installer.exe', 01:15:49 INFO - 'log_level': 'info', 01:15:49 INFO - 'log_name': 'talos', 01:15:49 INFO - 'log_to_console': True, 01:15:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:15:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:15:49 INFO - 'opt_config_files': (), 01:15:49 INFO - 'pip_index': False, 01:15:49 INFO - 'sps_profile': False, 01:15:49 INFO - 'sps_profile_interval': 0, 01:15:49 INFO - 'suite': 'dromaeojs', 01:15:49 INFO - 'system_bits': '32', 01:15:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:15:49 INFO - 'title': 'talos-linux64-ix-061', 01:15:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:15:49 INFO - 'use_talos_json': True, 01:15:49 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 01:15:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:15:49 INFO - 'work_dir': 'build'} 01:15:49 INFO - ##### 01:15:49 INFO - ##### Running clobber step. 01:15:49 INFO - ##### 01:15:49 INFO - Running pre-action listener: _resource_record_pre_action 01:15:49 INFO - Running main action method: clobber 01:15:49 INFO - rmtree: /builds/slave/test-pgo/build 01:15:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 01:15:49 INFO - Running post-action listener: _resource_record_post_action 01:15:49 INFO - ##### 01:15:49 INFO - ##### Running read-buildbot-config step. 01:15:49 INFO - ##### 01:15:49 INFO - Running pre-action listener: _resource_record_pre_action 01:15:49 INFO - Running main action method: read_buildbot_config 01:15:49 INFO - Using buildbot properties: 01:15:49 INFO - { 01:15:49 INFO - "properties": { 01:15:49 INFO - "buildnumber": 58, 01:15:49 INFO - "product": "firefox", 01:15:49 INFO - "script_repo_revision": "production", 01:15:49 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:15:49 INFO - "repository": "", 01:15:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 01:15:49 INFO - "buildid": "20160125070233", 01:15:49 INFO - "slavename": "talos-linux64-ix-061", 01:15:49 INFO - "pgo_build": "True", 01:15:49 INFO - "basedir": "/builds/slave/test-pgo", 01:15:49 INFO - "project": "", 01:15:49 INFO - "platform": "ubuntu64_hw", 01:15:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:15:49 INFO - "slavebuilddir": "test", 01:15:49 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:15:49 INFO - "branch": "mozilla-beta", 01:15:49 INFO - "repo_path": "releases/mozilla-beta", 01:15:49 INFO - "stage_platform": "linux64-pgo", 01:15:49 INFO - "builduid": "eb2ea0af6ac2494ca810680a4890723f", 01:15:49 INFO - "revision": "3bc504ea394f" 01:15:49 INFO - }, 01:15:49 INFO - "sourcestamp": { 01:15:49 INFO - "repository": "", 01:15:49 INFO - "hasPatch": false, 01:15:49 INFO - "project": "", 01:15:49 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:15:49 INFO - "changes": [ 01:15:49 INFO - { 01:15:49 INFO - "category": null, 01:15:49 INFO - "files": [ 01:15:49 INFO - { 01:15:49 INFO - "url": null, 01:15:49 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2" 01:15:49 INFO - } 01:15:49 INFO - ], 01:15:49 INFO - "repository": "", 01:15:49 INFO - "rev": "3bc504ea394f", 01:15:49 INFO - "who": "sendchange", 01:15:49 INFO - "when": 1453769833, 01:15:49 INFO - "number": 7514634, 01:15:49 INFO - "comments": "Bug 1241916 - Adds gn locale to browser/locales/shipped-locales. r=delphine a=sylvestre", 01:15:49 INFO - "project": "", 01:15:49 INFO - "at": "Mon 25 Jan 2016 16:57:13", 01:15:49 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:15:49 INFO - "revlink": "", 01:15:49 INFO - "properties": [ 01:15:49 INFO - [ 01:15:49 INFO - "buildid", 01:15:49 INFO - "20160125070233", 01:15:49 INFO - "Change" 01:15:49 INFO - ], 01:15:49 INFO - [ 01:15:49 INFO - "builduid", 01:15:49 INFO - "eb2ea0af6ac2494ca810680a4890723f", 01:15:49 INFO - "Change" 01:15:49 INFO - ], 01:15:49 INFO - [ 01:15:49 INFO - "pgo_build", 01:15:49 INFO - "True", 01:15:49 INFO - "Change" 01:15:49 INFO - ] 01:15:49 INFO - ], 01:15:49 INFO - "revision": "3bc504ea394f" 01:15:49 INFO - } 01:15:49 INFO - ], 01:15:49 INFO - "revision": "3bc504ea394f" 01:15:49 INFO - } 01:15:49 INFO - } 01:15:49 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2. 01:15:49 INFO - Running post-action listener: _resource_record_post_action 01:15:49 INFO - ##### 01:15:49 INFO - ##### Running download-and-extract step. 01:15:49 INFO - ##### 01:15:49 INFO - Running pre-action listener: _resource_record_pre_action 01:15:49 INFO - Running main action method: download_and_extract 01:15:49 INFO - mkdir: /builds/slave/test-pgo/build/tests 01:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:49 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json 01:15:49 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 01:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 01:15:49 INFO - Downloaded 1270 bytes. 01:15:49 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 01:15:49 INFO - Using the following test package requirements: 01:15:49 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:15:49 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:15:49 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:15:49 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:15:49 INFO - u'jsshell-linux-x86_64.zip'], 01:15:49 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:15:49 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:15:49 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:15:49 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:15:49 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 01:15:49 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:15:49 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 01:15:49 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:15:49 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:15:49 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:15:49 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:15:49 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:15:49 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:49 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:15:49 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:15:50 INFO - Downloaded 21580477 bytes. 01:15:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:15:50 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:15:50 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:15:51 INFO - Return code: 0 01:15:51 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:51 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:15:51 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:15:51 INFO - Downloaded 21580477 bytes. 01:15:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:15:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:15:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:15:53 INFO - Return code: 0 01:15:53 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')]} 01:15:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:15:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:15:53 INFO - Downloaded 11132038 bytes. 01:15:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 01:15:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:15:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 01:15:53 INFO - Return code: 0 01:15:53 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')]} 01:15:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:15:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:15:54 INFO - Downloaded 57947831 bytes. 01:15:54 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:15:54 INFO - mkdir: /builds/slave/test-pgo/properties 01:15:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 01:15:54 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 01:15:54 INFO - Contents: 01:15:54 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:15:54 INFO - Running post-action listener: _resource_record_post_action 01:15:54 INFO - Running post-action listener: set_extra_try_arguments 01:15:54 INFO - ##### 01:15:54 INFO - ##### Running populate-webroot step. 01:15:54 INFO - ##### 01:15:54 INFO - Running pre-action listener: _resource_record_pre_action 01:15:54 INFO - Running main action method: populate_webroot 01:15:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:15:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:15:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:15:54 INFO - '--tppagecycles', 01:15:54 INFO - '7'], 01:15:54 INFO - 'tests': ['tsvgm']}}, 01:15:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:15:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:15:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:15:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:15:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:15:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:15:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'tests': ['damp', 'tps']}, 01:15:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['damp', 'tps']}, 01:15:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:15:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:15:54 INFO - 'other': {'tests': ['a11yr', 01:15:54 INFO - 'ts_paint', 01:15:54 INFO - 'tpaint', 01:15:54 INFO - 'sessionrestore', 01:15:54 INFO - 'sessionrestore_no_auto_restore']}, 01:15:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['a11yr', 01:15:54 INFO - 'ts_paint', 01:15:54 INFO - 'tpaint', 01:15:54 INFO - 'sessionrestore', 01:15:54 INFO - 'sessionrestore_no_auto_restore']}, 01:15:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['a11yr', 01:15:54 INFO - 'ts_paint', 01:15:54 INFO - 'tpaint', 01:15:54 INFO - 'sessionrestore', 01:15:54 INFO - 'sessionrestore_no_auto_restore']}, 01:15:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['a11yr', 01:15:54 INFO - 'ts_paint', 01:15:54 INFO - 'tpaint', 01:15:54 INFO - 'sessionrestore', 01:15:54 INFO - 'sessionrestore_no_auto_restore']}, 01:15:54 INFO - 'other_l64': {'tests': ['a11yr', 01:15:54 INFO - 'ts_paint', 01:15:54 INFO - 'tpaint', 01:15:54 INFO - 'sessionrestore', 01:15:54 INFO - 'sessionrestore_no_auto_restore']}, 01:15:54 INFO - 'other_nol64': {'tests': ['a11yr', 01:15:54 INFO - 'ts_paint', 01:15:54 INFO - 'tpaint', 01:15:54 INFO - 'sessionrestore', 01:15:54 INFO - 'sessionrestore_no_auto_restore']}, 01:15:54 INFO - 'svgr': {'tests': ['tsvgx', 01:15:54 INFO - 'tsvgr_opacity', 01:15:54 INFO - 'tart', 01:15:54 INFO - 'tscrollx', 01:15:54 INFO - 'cart']}, 01:15:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['tsvgx', 01:15:54 INFO - 'tsvgr_opacity', 01:15:54 INFO - 'tart', 01:15:54 INFO - 'tscrollx', 01:15:54 INFO - 'cart']}, 01:15:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'tests': ['tp5o']}, 01:15:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'talos_options': ['--e10s'], 01:15:54 INFO - 'tests': ['tp5o']}, 01:15:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'talos_options': ['--xperf_path', 01:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:15:54 INFO - 'tests': ['tp5n']}, 01:15:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:54 INFO - 'talos_options': ['--e10s', 01:15:54 INFO - '--xperf_path', 01:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:15:54 INFO - 'tests': ['tp5n']}}, 01:15:54 INFO - 'talos.zip': {'path': '', 01:15:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:15:54 INFO - Running post-action listener: _resource_record_post_action 01:15:54 INFO - ##### 01:15:54 INFO - ##### Running create-virtualenv step. 01:15:54 INFO - ##### 01:15:54 INFO - Running pre-action listener: _resource_record_pre_action 01:15:54 INFO - Running main action method: create_virtualenv 01:15:54 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 01:15:54 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 01:15:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 01:15:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:15:54 INFO - Using real prefix '/usr' 01:15:54 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 01:15:55 INFO - Installing distribute.............................................................................................................................................................................................done. 01:15:57 INFO - Installing pip.................done. 01:15:57 INFO - Return code: 0 01:15:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 01:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:15:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 01:15:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:15:57 INFO - Using env: {'DISPLAY': ':0', 01:15:57 INFO - 'HOME': '/home/cltbld', 01:15:57 INFO - 'LANG': 'en_US.UTF-8', 01:15:57 INFO - 'LANGUAGE': 'en_US:en', 01:15:57 INFO - 'LOGNAME': 'cltbld', 01:15:57 INFO - 'MAIL': '/var/mail/cltbld', 01:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:57 INFO - 'MOZ_NO_REMOTE': '1', 01:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:57 INFO - 'NO_EM_RESTART': '1', 01:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:15:57 INFO - 'PWD': '/builds/slave/test-pgo', 01:15:57 INFO - 'SHELL': '/bin/bash', 01:15:57 INFO - 'SHLVL': '1', 01:15:57 INFO - 'TERM': 'linux', 01:15:57 INFO - 'TMOUT': '86400', 01:15:57 INFO - 'USER': 'cltbld', 01:15:57 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:57 INFO - '_': '/tools/buildbot/bin/python'} 01:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:57 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 01:15:57 INFO - Cleaning up... 01:15:57 INFO - Return code: 0 01:15:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 01:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:15:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 01:15:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:15:57 INFO - Using env: {'DISPLAY': ':0', 01:15:57 INFO - 'HOME': '/home/cltbld', 01:15:57 INFO - 'LANG': 'en_US.UTF-8', 01:15:57 INFO - 'LANGUAGE': 'en_US:en', 01:15:57 INFO - 'LOGNAME': 'cltbld', 01:15:57 INFO - 'MAIL': '/var/mail/cltbld', 01:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:57 INFO - 'MOZ_NO_REMOTE': '1', 01:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:57 INFO - 'NO_EM_RESTART': '1', 01:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:15:57 INFO - 'PWD': '/builds/slave/test-pgo', 01:15:57 INFO - 'SHELL': '/bin/bash', 01:15:57 INFO - 'SHLVL': '1', 01:15:57 INFO - 'TERM': 'linux', 01:15:57 INFO - 'TMOUT': '86400', 01:15:57 INFO - 'USER': 'cltbld', 01:15:57 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:57 INFO - '_': '/tools/buildbot/bin/python'} 01:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:57 INFO - Downloading/unpacking psutil>=0.7.1 01:15:57 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 01:15:57 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 01:15:57 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 01:15:57 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 01:15:57 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 01:15:57 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 01:15:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 01:15:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:15:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:15:59 INFO - Installing collected packages: psutil 01:15:59 INFO - Running setup.py install for psutil 01:15:59 INFO - building 'psutil._psutil_linux' extension 01:15:59 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 01:15:59 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 01:15:59 INFO - building 'psutil._psutil_posix' extension 01:15:59 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 01:15:59 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 01:16:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:16:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:16:00 INFO - Successfully installed psutil 01:16:00 INFO - Cleaning up... 01:16:00 INFO - Return code: 0 01:16:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 01:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:16:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 01:16:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 01:16:00 INFO - Using env: {'DISPLAY': ':0', 01:16:00 INFO - 'HOME': '/home/cltbld', 01:16:00 INFO - 'LANG': 'en_US.UTF-8', 01:16:00 INFO - 'LANGUAGE': 'en_US:en', 01:16:00 INFO - 'LOGNAME': 'cltbld', 01:16:00 INFO - 'MAIL': '/var/mail/cltbld', 01:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:00 INFO - 'MOZ_NO_REMOTE': '1', 01:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:00 INFO - 'NO_EM_RESTART': '1', 01:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:00 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:00 INFO - 'SHELL': '/bin/bash', 01:16:00 INFO - 'SHLVL': '1', 01:16:00 INFO - 'TERM': 'linux', 01:16:00 INFO - 'TMOUT': '86400', 01:16:00 INFO - 'USER': 'cltbld', 01:16:00 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:00 INFO - '_': '/tools/buildbot/bin/python'} 01:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:16:00 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 01:16:00 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 01:16:00 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 01:16:00 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 01:16:00 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 01:16:00 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 01:16:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:16:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:16:02 INFO - Installing collected packages: mozsystemmonitor 01:16:02 INFO - Running setup.py install for mozsystemmonitor 01:16:02 INFO - Successfully installed mozsystemmonitor 01:16:02 INFO - Cleaning up... 01:16:02 INFO - Return code: 0 01:16:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 01:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:16:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 01:16:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:16:02 INFO - Using env: {'DISPLAY': ':0', 01:16:02 INFO - 'HOME': '/home/cltbld', 01:16:02 INFO - 'LANG': 'en_US.UTF-8', 01:16:02 INFO - 'LANGUAGE': 'en_US:en', 01:16:02 INFO - 'LOGNAME': 'cltbld', 01:16:02 INFO - 'MAIL': '/var/mail/cltbld', 01:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:02 INFO - 'MOZ_NO_REMOTE': '1', 01:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:02 INFO - 'NO_EM_RESTART': '1', 01:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:02 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:02 INFO - 'SHELL': '/bin/bash', 01:16:02 INFO - 'SHLVL': '1', 01:16:02 INFO - 'TERM': 'linux', 01:16:02 INFO - 'TMOUT': '86400', 01:16:02 INFO - 'USER': 'cltbld', 01:16:02 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:02 INFO - '_': '/tools/buildbot/bin/python'} 01:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:02 INFO - Downloading/unpacking blobuploader==1.2.4 01:16:02 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 01:16:02 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 01:16:02 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 01:16:02 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 01:16:02 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 01:16:02 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 01:16:04 INFO - Downloading blobuploader-1.2.4.tar.gz 01:16:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:16:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 01:16:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:04 INFO - Downloading docopt-0.6.1.tar.gz 01:16:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 01:16:05 INFO - Installing collected packages: blobuploader, requests, docopt 01:16:05 INFO - Running setup.py install for blobuploader 01:16:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:16:05 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 01:16:05 INFO - Running setup.py install for requests 01:16:05 INFO - Running setup.py install for docopt 01:16:05 INFO - Successfully installed blobuploader requests docopt 01:16:05 INFO - Cleaning up... 01:16:05 INFO - Return code: 0 01:16:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:16:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 01:16:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:16:05 INFO - Using env: {'DISPLAY': ':0', 01:16:05 INFO - 'HOME': '/home/cltbld', 01:16:05 INFO - 'LANG': 'en_US.UTF-8', 01:16:05 INFO - 'LANGUAGE': 'en_US:en', 01:16:05 INFO - 'LOGNAME': 'cltbld', 01:16:05 INFO - 'MAIL': '/var/mail/cltbld', 01:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:05 INFO - 'MOZ_NO_REMOTE': '1', 01:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:05 INFO - 'NO_EM_RESTART': '1', 01:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:05 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:05 INFO - 'SHELL': '/bin/bash', 01:16:05 INFO - 'SHLVL': '1', 01:16:05 INFO - 'TERM': 'linux', 01:16:05 INFO - 'TMOUT': '86400', 01:16:05 INFO - 'USER': 'cltbld', 01:16:05 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:05 INFO - '_': '/tools/buildbot/bin/python'} 01:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:16:05 INFO - Running setup.py (path:/tmp/pip-huxEeW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:16:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:16:05 INFO - Running setup.py (path:/tmp/pip-4ODXJa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:16:06 INFO - Running setup.py (path:/tmp/pip-QBdm5O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:16:06 INFO - Running setup.py (path:/tmp/pip-a_Vz3f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:16:06 INFO - Running setup.py (path:/tmp/pip-qf4anY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:16:06 INFO - Running setup.py (path:/tmp/pip-dsc6II-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:16:06 INFO - Running setup.py (path:/tmp/pip-cVHkxT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:16:06 INFO - Running setup.py (path:/tmp/pip-hBCOyt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:16:06 INFO - Running setup.py (path:/tmp/pip-5Fuvn9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:16:06 INFO - Running setup.py (path:/tmp/pip-QuG72C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:16:06 INFO - Running setup.py (path:/tmp/pip-7zRixi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:16:06 INFO - Running setup.py (path:/tmp/pip-Wpo_UH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:16:06 INFO - Running setup.py (path:/tmp/pip-FfFrQj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:16:06 INFO - Running setup.py (path:/tmp/pip-Q6nhIy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:16:06 INFO - Running setup.py (path:/tmp/pip-FHPr_c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:16:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:16:06 INFO - Running setup.py (path:/tmp/pip-9zEJ3J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:16:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:16:07 INFO - Running setup.py (path:/tmp/pip-K9nVWX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:16:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:16:07 INFO - Running setup.py install for manifestparser 01:16:07 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Running setup.py install for mozcrash 01:16:07 INFO - Running setup.py install for mozdebug 01:16:07 INFO - Running setup.py install for mozdevice 01:16:07 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Running setup.py install for mozfile 01:16:07 INFO - Running setup.py install for mozhttpd 01:16:07 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Running setup.py install for mozinfo 01:16:07 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Running setup.py install for mozInstall 01:16:07 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 01:16:07 INFO - Running setup.py install for mozleak 01:16:07 INFO - Running setup.py install for mozlog 01:16:08 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 01:16:08 INFO - Running setup.py install for moznetwork 01:16:08 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 01:16:08 INFO - Running setup.py install for mozprocess 01:16:08 INFO - Running setup.py install for mozprofile 01:16:08 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 01:16:08 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 01:16:08 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 01:16:08 INFO - Running setup.py install for mozrunner 01:16:08 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 01:16:08 INFO - Running setup.py install for mozscreenshot 01:16:08 INFO - Running setup.py install for moztest 01:16:08 INFO - Running setup.py install for mozversion 01:16:08 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 01:16:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:16:08 INFO - Cleaning up... 01:16:08 INFO - Return code: 0 01:16:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:16:08 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')]} 01:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:08 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')]} 01:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:16:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 01:16:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:16:08 INFO - Using env: {'DISPLAY': ':0', 01:16:08 INFO - 'HOME': '/home/cltbld', 01:16:08 INFO - 'LANG': 'en_US.UTF-8', 01:16:08 INFO - 'LANGUAGE': 'en_US:en', 01:16:08 INFO - 'LOGNAME': 'cltbld', 01:16:08 INFO - 'MAIL': '/var/mail/cltbld', 01:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:08 INFO - 'MOZ_NO_REMOTE': '1', 01:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:08 INFO - 'NO_EM_RESTART': '1', 01:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:08 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:08 INFO - 'SHELL': '/bin/bash', 01:16:08 INFO - 'SHLVL': '1', 01:16:08 INFO - 'TERM': 'linux', 01:16:08 INFO - 'TMOUT': '86400', 01:16:08 INFO - 'USER': 'cltbld', 01:16:08 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:08 INFO - '_': '/tools/buildbot/bin/python'} 01:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:16:09 INFO - Running setup.py (path:/tmp/pip-KJE0ra-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:16:09 INFO - Running setup.py (path:/tmp/pip-_aTA9U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:16:09 INFO - Running setup.py (path:/tmp/pip-Eio9Pw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:16:09 INFO - Running setup.py (path:/tmp/pip-9ZWwKs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:16:09 INFO - Running setup.py (path:/tmp/pip-GzDeKX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:16:09 INFO - Running setup.py (path:/tmp/pip-AcIIAI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:16:09 INFO - Running setup.py (path:/tmp/pip-7JB2yL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:16:09 INFO - Running setup.py (path:/tmp/pip-h3R1gc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:16:09 INFO - Running setup.py (path:/tmp/pip-Z5ITHY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:16:09 INFO - Running setup.py (path:/tmp/pip-xRl6FD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:16:09 INFO - Running setup.py (path:/tmp/pip-rbDSjk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:16:09 INFO - Running setup.py (path:/tmp/pip-A2_bgE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:16:09 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)) 01:16:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:16:09 INFO - Running setup.py (path:/tmp/pip-4h8Owk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:16:10 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)) 01:16:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:16:10 INFO - Running setup.py (path:/tmp/pip-7m1oao-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:16:10 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)) 01:16:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:16:10 INFO - Running setup.py (path:/tmp/pip-fm97Xu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:16:10 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)) 01:16:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:16:10 INFO - Running setup.py (path:/tmp/pip-Na3Nea-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:16:10 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)) 01:16:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:16:10 INFO - Running setup.py (path:/tmp/pip-AXKho2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:16:10 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 01:16:10 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 01:16:10 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 01:16:10 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 01:16:10 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 01:16:10 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 01:16:12 INFO - Downloading blessings-1.6.tar.gz 01:16:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 01:16:12 INFO - Installing collected packages: blessings 01:16:12 INFO - Running setup.py install for blessings 01:16:12 INFO - Successfully installed blessings 01:16:12 INFO - Cleaning up... 01:16:12 INFO - Return code: 0 01:16:12 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 01:16:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:16:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:16:12 INFO - Reading from file tmpfile_stdout 01:16:12 INFO - Current package versions: 01:16:12 INFO - argparse == 1.2.1 01:16:12 INFO - blessings == 1.6 01:16:12 INFO - blobuploader == 1.2.4 01:16:12 INFO - docopt == 0.6.1 01:16:12 INFO - manifestparser == 1.1 01:16:12 INFO - mozInstall == 1.12 01:16:12 INFO - mozcrash == 0.16 01:16:12 INFO - mozdebug == 0.1 01:16:12 INFO - mozdevice == 0.47 01:16:12 INFO - mozfile == 1.2 01:16:12 INFO - mozhttpd == 0.7 01:16:12 INFO - mozinfo == 0.9 01:16:12 INFO - mozleak == 0.1 01:16:12 INFO - mozlog == 3.1 01:16:12 INFO - moznetwork == 0.27 01:16:12 INFO - mozprocess == 0.22 01:16:12 INFO - mozprofile == 0.27 01:16:12 INFO - mozrunner == 6.11 01:16:12 INFO - mozscreenshot == 0.1 01:16:12 INFO - mozsystemmonitor == 0.0 01:16:12 INFO - moztest == 0.7 01:16:12 INFO - mozversion == 1.4 01:16:12 INFO - psutil == 3.1.1 01:16:12 INFO - requests == 1.2.3 01:16:12 INFO - wsgiref == 0.1.2 01:16:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:16:12 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')]} 01:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:12 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')]} 01:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:16:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 01:16:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:16:12 INFO - Using env: {'DISPLAY': ':0', 01:16:12 INFO - 'HOME': '/home/cltbld', 01:16:12 INFO - 'LANG': 'en_US.UTF-8', 01:16:12 INFO - 'LANGUAGE': 'en_US:en', 01:16:12 INFO - 'LOGNAME': 'cltbld', 01:16:12 INFO - 'MAIL': '/var/mail/cltbld', 01:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:12 INFO - 'MOZ_NO_REMOTE': '1', 01:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:12 INFO - 'NO_EM_RESTART': '1', 01:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:12 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:12 INFO - 'SHELL': '/bin/bash', 01:16:12 INFO - 'SHLVL': '1', 01:16:12 INFO - 'TERM': 'linux', 01:16:12 INFO - 'TMOUT': '86400', 01:16:12 INFO - 'USER': 'cltbld', 01:16:12 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:12 INFO - '_': '/tools/buildbot/bin/python'} 01:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 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)) 01:16:12 INFO - Cleaning up... 01:16:12 INFO - Return code: 0 01:16:12 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 01:16:12 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')]} 01:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:12 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')]} 01:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:16:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 01:16:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:16:12 INFO - Using env: {'DISPLAY': ':0', 01:16:12 INFO - 'HOME': '/home/cltbld', 01:16:12 INFO - 'LANG': 'en_US.UTF-8', 01:16:12 INFO - 'LANGUAGE': 'en_US:en', 01:16:12 INFO - 'LOGNAME': 'cltbld', 01:16:12 INFO - 'MAIL': '/var/mail/cltbld', 01:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:12 INFO - 'MOZ_NO_REMOTE': '1', 01:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:12 INFO - 'NO_EM_RESTART': '1', 01:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:12 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:12 INFO - 'SHELL': '/bin/bash', 01:16:12 INFO - 'SHLVL': '1', 01:16:12 INFO - 'TERM': 'linux', 01:16:12 INFO - 'TMOUT': '86400', 01:16:12 INFO - 'USER': 'cltbld', 01:16:12 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:12 INFO - '_': '/tools/buildbot/bin/python'} 01:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:12 INFO - Downloading/unpacking jsonschema 01:16:12 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 01:16:12 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 01:16:12 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 01:16:12 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 01:16:12 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 01:16:12 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 01:16:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:16:14 INFO - Downloading/unpacking functools32 (from jsonschema) 01:16:14 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 01:16:14 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 01:16:14 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 01:16:14 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 01:16:14 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 01:16:14 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 01:16:14 INFO - Downloading functools32-3.2.3-2.tar.gz 01:16:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 01:16:14 INFO - warning: no files found matching '*.txt' 01:16:14 INFO - no previously-included directories found matching 'build' 01:16:14 INFO - no previously-included directories found matching 'dist' 01:16:14 INFO - no previously-included directories found matching '.git*' 01:16:14 INFO - Installing collected packages: jsonschema, functools32 01:16:15 INFO - Running setup.py install for functools32 01:16:15 INFO - warning: no files found matching '*.txt' 01:16:15 INFO - no previously-included directories found matching 'build' 01:16:15 INFO - no previously-included directories found matching 'dist' 01:16:15 INFO - no previously-included directories found matching '.git*' 01:16:15 INFO - Successfully installed jsonschema functools32 01:16:15 INFO - Cleaning up... 01:16:15 INFO - Return code: 0 01:16:15 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 01:16: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')]} 01:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16: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')]} 01:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc36139f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', '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 01:16:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 01:16:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:16:15 INFO - Using env: {'DISPLAY': ':0', 01:16:15 INFO - 'HOME': '/home/cltbld', 01:16:15 INFO - 'LANG': 'en_US.UTF-8', 01:16:15 INFO - 'LANGUAGE': 'en_US:en', 01:16:15 INFO - 'LOGNAME': 'cltbld', 01:16:15 INFO - 'MAIL': '/var/mail/cltbld', 01:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:15 INFO - 'MOZ_NO_REMOTE': '1', 01:16:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:15 INFO - 'NO_EM_RESTART': '1', 01:16:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:15 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:15 INFO - 'SHELL': '/bin/bash', 01:16:15 INFO - 'SHLVL': '1', 01:16:15 INFO - 'TERM': 'linux', 01:16:15 INFO - 'TMOUT': '86400', 01:16:15 INFO - 'USER': 'cltbld', 01:16:15 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:15 INFO - '_': '/tools/buildbot/bin/python'} 01:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:15 INFO - Downloading/unpacking flake8 01:16:15 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 01:16:15 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 01:16:15 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 01:16:15 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 01:16:15 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 01:16:15 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 01:16:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:16:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:16:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 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 01:16:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 01:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:16:17 INFO - no previously-included directories found matching 'docs/_build' 01:16:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:16:17 INFO - Running setup.py install for pep8 01:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:16:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:16:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:16:17 INFO - no previously-included directories found matching 'docs/_build' 01:16:18 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 01:16:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:16:18 INFO - Cleaning up... 01:16:18 INFO - Return code: 0 01:16:18 INFO - Running post-action listener: _resource_record_post_action 01:16:18 INFO - Running post-action listener: _start_resource_monitoring 01:16:18 INFO - Starting resource monitoring. 01:16:18 INFO - ##### 01:16:18 INFO - ##### Running install step. 01:16:18 INFO - ##### 01:16:18 INFO - Running pre-action listener: _resource_record_pre_action 01:16:18 INFO - Running main action method: install 01:16:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:16:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:16:18 INFO - Reading from file tmpfile_stdout 01:16:18 INFO - Detecting whether we're running mozinstall >=1.0... 01:16:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 01:16:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 01:16:18 INFO - Reading from file tmpfile_stdout 01:16:18 INFO - Output received: 01:16:18 INFO - Usage: mozinstall [options] installer 01:16:18 INFO - Options: 01:16:18 INFO - -h, --help show this help message and exit 01:16:18 INFO - -d DEST, --destination=DEST 01:16:18 INFO - Directory to install application into. [default: 01:16:18 INFO - "/builds/slave/test-pgo"] 01:16:18 INFO - --app=APP Application being installed. [default: firefox] 01:16:18 INFO - mkdir: /builds/slave/test-pgo/build/application 01:16:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 01:16:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 01:16:32 INFO - Reading from file tmpfile_stdout 01:16:32 INFO - Output received: 01:16:32 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 01:16:32 INFO - Running post-action listener: _resource_record_post_action 01:16:32 INFO - ##### 01:16:32 INFO - ##### Running run-tests step. 01:16:32 INFO - ##### 01:16:32 INFO - Running pre-action listener: _resource_record_pre_action 01:16:32 INFO - Running main action method: run_tests 01:16:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 01:16:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 01:16:32 INFO - Python 2.7.3 01:16:32 INFO - Return code: 0 01:16:32 INFO - grabbing minidump binary from tooltool 01:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:32 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 0x2705350>, '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 0x2706250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27066e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 01:16:32 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 01:16:32 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 01:16:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:16:33 INFO - Return code: 0 01:16:33 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 01:16:33 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 01:16:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 01:16:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 01:16:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 01:16:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 01:16:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 01:16:34 INFO - Return code: 0 01:16:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 01:16:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost 01:16:34 INFO - Using env: {'DISPLAY': ':0', 01:16:34 INFO - 'HOME': '/home/cltbld', 01:16:34 INFO - 'LANG': 'en_US.UTF-8', 01:16:34 INFO - 'LANGUAGE': 'en_US:en', 01:16:34 INFO - 'LOGNAME': 'cltbld', 01:16:34 INFO - 'MAIL': '/var/mail/cltbld', 01:16:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:16:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 01:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:34 INFO - 'MOZ_NO_REMOTE': '1', 01:16:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:34 INFO - 'NO_EM_RESTART': '1', 01:16:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:16:34 INFO - 'PWD': '/builds/slave/test-pgo', 01:16:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 01:16:34 INFO - 'SHELL': '/bin/bash', 01:16:34 INFO - 'SHLVL': '1', 01:16:34 INFO - 'TERM': 'linux', 01:16:34 INFO - 'TMOUT': '86400', 01:16:34 INFO - 'USER': 'cltbld', 01:16:34 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581', 01:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:34 INFO - '_': '/tools/buildbot/bin/python'} 01:16:34 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost'] with output_timeout 3600 01:16:34 INFO - mozversion INFO | application_buildid: 20160125070233 01:16:34 INFO - mozversion INFO | application_changeset: 3bc504ea394fe54d1dbb3afc86534b25c36432bd 01:16:34 INFO - mozversion INFO | application_display_name: Nightly 01:16:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:16:34 INFO - mozversion INFO | application_name: Firefox 01:16:34 INFO - mozversion INFO | application_remotingname: firefox 01:16:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:16:34 INFO - mozversion INFO | application_vendor: Mozilla 01:16:34 INFO - mozversion INFO | application_version: 45.0 01:16:34 INFO - mozversion INFO | platform_buildid: 20160125070233 01:16:34 INFO - mozversion INFO | platform_changeset: 3bc504ea394fe54d1dbb3afc86534b25c36432bd 01:16:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:16:34 INFO - mozversion INFO | platform_version: 45.0 01:16:34 INFO - 2016-01-26 01:16:34,301 DEBUG : using testdate: 1453799794 01:16:34 INFO - 2016-01-26 01:16:34,301 DEBUG : actual date: 1453799794 01:16:34 INFO - 2016-01-26 01:16:34,311 INFO : starting webserver on 'localhost:53031' 01:16:34 INFO - 2016-01-26 01:16:34,312 INFO : Starting test suite talos-linux64-ix-061 01:16:34 INFO - 2016-01-26 01:16:34,312 INFO : Starting test dromaeo_css 01:16:34 INFO - 2016-01-26 01:16:34,312 DEBUG : operating with platform_type : linux_ 01:16:34 INFO - 2016-01-26 01:16:34,312 INFO : Initialising browser for dromaeo_css test... 01:16:34 INFO - 2016-01-26 01:16:34,328 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4vdxOU/profile http://localhost:53031/getInfo.html 01:16:38 INFO - 2016-01-26 01:16:38,406 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:16:38 INFO - 2016-01-26 01:16:38,406 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:16:38 INFO - 2016-01-26 01:16:38,406 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:16:38 INFO - 2016-01-26 01:16:38,406 DEBUG : BROWSER_OUTPUT: __metrics 01:16:38 INFO - 2016-01-26 01:16:38,563 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:16:39 INFO - 2016-01-26 01:16:39,697 INFO : Browser initialized. 01:16:39 INFO - 2016-01-26 01:16:39,698 INFO : Running cycle 1/1 for dromaeo_css test... 01:16:39 INFO - 2016-01-26 01:16:39,698 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4vdxOU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:16:51 INFO - 2016-01-26 01:16:51,496 DEBUG : BROWSER_OUTPUT: RSS: Main: 134021120 01:16:51 INFO - 2016-01-26 01:16:51,497 DEBUG : BROWSER_OUTPUT: 01:17:42 INFO - 2016-01-26 01:17:42,344 DEBUG : BROWSER_OUTPUT: 1453799862339 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] 01:19:50 INFO - 2016-01-26 01:19:50,662 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53031/tests/dromaeo/cssquery-ext.html) 01:19:51 INFO - 2016-01-26 01:19:51,747 DEBUG : BROWSER_OUTPUT: RSS: Main: 240799744 01:19:51 INFO - 2016-01-26 01:19:51,747 DEBUG : BROWSER_OUTPUT: 01:22:53 INFO - 2016-01-26 01:22:53,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/dromaeo/cssquery-ext.html (next: http://localhost:53031/tests/dromaeo/cssquery-jquery.html) 01:22:54 INFO - 2016-01-26 01:22:54,376 DEBUG : BROWSER_OUTPUT: RSS: Main: 215105536 01:22:54 INFO - 2016-01-26 01:22:54,376 DEBUG : BROWSER_OUTPUT: 01:25:56 INFO - 2016-01-26 01:25:56,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53031/tests/dromaeo/cssquery-mootools.html) 01:25:56 INFO - 2016-01-26 01:25:56,620 DEBUG : BROWSER_OUTPUT: RSS: Main: 207835136 01:25:56 INFO - 2016-01-26 01:25:56,620 DEBUG : BROWSER_OUTPUT: 01:28:58 INFO - 2016-01-26 01:28:58,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53031/tests/dromaeo/cssquery-prototype.html) 01:28:58 INFO - 2016-01-26 01:28:58,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 196571136 01:28:58 INFO - 2016-01-26 01:28:58,836 DEBUG : BROWSER_OUTPUT: 01:32:01 INFO - 2016-01-26 01:32:01,199 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53031/tests/dromaeo/cssquery-yui.html) 01:32:01 INFO - 2016-01-26 01:32:01,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 193388544 01:32:01 INFO - 2016-01-26 01:32:01,785 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,011 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/dromaeo/cssquery-yui.html (next: http://localhost:53031/tests/dromaeo/cssquery-dojo.html) 01:35:04 INFO - 2016-01-26 01:35:04,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 242769920 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3318.0377690469763;3374.8312584370774;3405.7105146062568;3407.1652445150944;3425.1950791563977;5547.916781248275;5598.7200639968;5599.1041433370665;5612.971935140324;5613.69124698142;5628.0713682242385;5636.267285252911;5637.267155269809;5641.68970706611;5646.322441307038;4978.875528111808;5003.4246061702825;5090.898182036357;5121.078205922932;5184.7148406837605;5050.368703912011;5131.512506311894;5150.459201783796;5155.664881782672;5162.354705661793;5089.821856235032;5155.278261043438;5194.402643695986;5210.583153347723;5215.40022897366;5188.04021256067;5205.479452054783;5206.895862082737;5213.452587478336;5246.527812496894;4910.116179087763;4957.405111386656;4999.575036121934;5090.567301779353;5092.7453627318855;5392.245085688007;5399.1091469907415;5402.270693456372;5403.78384864605;5417.45825417459;5950.821475355703;5968.820935371946;6000.819975400745;6009.068594367836;6016.4885984691355;5849.917765213407;5875.353711091777;5974.133750606138;6033.728482218266;6047.425494578008;5617.9438205618235;5627.634203776742;5629.127485239754;5632.211490391349;5634.605577609569;6089.786857459968;6106.603070800384;6110.694465276719;6118.021116621319;6120.112583675431;5733.283339582552;5842.620229685057;5848.473637372657;5955.970220148872;6002.969985150046;6070.332263451017;6073.4837538808815;6076.605020673642;6079;6080.2399700037495;5864.266966629171;5999.460048595647;6048.425399587032;6051.243594550681;6052.57631965767;5882.176495290662;5948.7323070461935;5962.40375962409;5966.820995370059;6046.848828779316;4638.652101092432;4661.044485880454;4675.064987002613;4676.5791078802395;4705.55297246761;4049.595040495986;4129.339305711072;4147.543770185277;4198.475190601175;4243.787810609457;5603.663780173202;5666.093425051972;5671.602987790815;5680.460356266148;5709.628874123168;6158.076288556752;6188.257409110935;6191.938080619227;6197.411245931629;6198.597091189058;5581.581381396411;5633.971830140823;5692.0608099663095;5845.357010728816;5849.795257165979;5319.946800532022;5342.813001545005;5343.332083489564;5351.464853514695;5370.62405631602;6309.558330916791;6325.557210995186;6326.177596912464;6328.208973878265;6363.777267795697;5799.362070172193;5930.436608522184;5994.400559944145;6140.17107690456;6169.167162433194;6260.718267677874;6274.247090349188;6278.623282603058;6279;6287.874242515216;5993.400659933972;6043.758249669964;6045.576809623284;6064.818055458343;6068.575199736153;5968.970155149196;6010.098485227251;6113.728279848454;6124.615591652548;6132.509399248139;5944.405559444021;5947.553933454921;5954.225950626391;5956;5962.493188079062;5667.631603945731;5922.170896074467;5926.585139040399;5926.644401335933;5932.080527518242;5679.119736440858;5722.284714410699;5753.424657534213;5758.280214973129;5761.827145185651;5896.439838215363;5930.377310382521;5950.137230101697;5955.851103722268;5966.940330596812;5652.971735141298;5830.912536312044;5836.387179346278;5839.211706419579;5840.2991641003355 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: |1;ext.html;579.7970710251412;619.845038740315;626.0234034905546;626.6709977261934;628.481502760223;31740;32514.18714532138;32558.348833023327;32580.348393032156;32595.18512037194;41474.377884331756;42339;42448;42501.362479562755;42503;9068.183863452288;9171.862422063674;9209.263258939302;9217.124373184537;9217.446953182796;8145.226203510657;8368.707095251668;9048.185663290302;9157.45055296681;10946.726331841728;10824.78350432991;10915.617953371633;10919.890801091968;10945.945270273638;10950.39772812495;10833.783324333508;10938.726531836688;10949.069329107035;10950.945245273802;10953.671389858317;10665.52005159762;10750.354978701303;10773.4613269337;10787.67636970892;10798.082163016154;43790.78104609456;44103.558964410266;44161.11677766427;44165;44217.77891110524;3566.0549954261883;3695.297893400272;3748.6813620842263;3799.7530160539477;4053.4527838741687;32627.347453050807;33037.33925321504;33456.1635959103;33515.49726754101;33591.32817343664;4178.353612888407;4243.427137336451;4247.575242475758;4258.808353624095;4318.589733975267;302886;309495;310077;310094;310818;58438.70780646155;59395.40604593899;59513.404865951656;71141.64429177821;72459;69587;70232;70578;70586.64706676434;70640.6467967657;18302.176402061938;18471.630567388576;18517.629647406975;18519.629607407776;18524;13948.302584870919;14117.43530258778;14122.71754564903;14254.786178207336;14256.144631322153;23498.29505114815;23612.881935590212;23641;23745.643815342784;23767.643485347733;8850.557472126367;8982.775430614287;8987.46075235488;9006.414583052081;9058.592363343534;1014.3153371474225;1047.701405099543;1047.7275908263907;1079.7570546626946;1212.7331986962854;30371;31527.21181970469;34470.31059378798;34779;35415.29169416597;1055.3984228989402;1061.2995423020768;1144.986686782187;1162.1632424654404;1182.408795602199;14464.710705786036;15520.689586208213;15903.840961590467;17340.566485837953;18160.636787264182;482495;483205;485202;489578;490021;61498;62049.689751550955;62177.68911155415;62178;62232.377676223565;1349.7705390083709;1411.386047069528;1448.2469116059801;1454.6581553334959;1480.2376775960388;31837;32435;32521.674783251863;32526.674733253312;32647.347053059282;51832.48167518277;52592.737036314575;52640.47359526356;52755.20867187072;52890.47109528856;312262;316565;317551;319983;320367;35348;35757;35834.8208258957;35927.82036089907;36128.8193559041;46353.768231158625;47098.822529437035;47211.76394118008;47329.29006064981;47874;39176.41235381414;39573.80213098916;39674.40488392619;45257.32114018358;45537.544624554655;41978;42142.57857421509;42227.155456890076;42833.357499636906;43049.78475107605;41044;41696.583034170486;41913;41980;42015.78992105142;35741.82129089338;35756.51652851335;35899.641003590674;36773;36989.26021479609;28258.49396478157;28482.85758571194;28553;28578;28714 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: |2;jquery.html;387.9747816391935;388.6424489469688;389.7427697719504;390.2818813383374;391.64751723448893;15002.399904003827;16748.246328915197;16880.83119168808;17858.107094645282;17903.462896113106;16921.407750728726;17238.569035774082;17253.568660783487;18395.264189432408;18657.720134197963;5015.799368025284;5116.2069879168785;5119.462456442076;5131.871703207413;5139.15203991341;3950.3679411294197;4072.7149099563044;4075.32757095079;5065.721385323805;5427.375851777037;5591.776328946858;5645.520130788888;5657.23627310314;5662.631928924628;5671.8014869479575;5149.2791009258735;5323.627346085776;5324.015057214408;5324.1747529133045;5330.466953304658;5102.464241254652;5110.10573149701;5122.948770512322;5296.417394086648;5347.358317001946;13100.60698179056;13347.46610135603;13354.532591359353;18359.908200459045;21110.15559377623;2047.5597746484577;2198.1097655449566;2320.9651855222014;2426.1387207541247;2475.405902583367;9931.106200441922;10021.321961620506;10140.89859101407;10159.4920253987;13225.074244802867;1778.9021603811784;1782.189103957696;1822.7630408046869;1831.258340372138;1844.1240410804894;60437;64354.678226608565;64417.67791161014;64504.35495645077;64548.3545164547;11086.16853735973;11096.61161859331;11127.833082503768;11135.610253641084;11165.553377864957;14667.853321466862;14770.113793172444;14783.70432591342;14784.186869722269;14810.703785924221;8666.610002549774;8676.78308042304;8686.218240358397;8735.126487351341;8769.123087691307;8652.826943461096;8689.261412779859;8699.956500217459;8781;8794.824103518022;4418.9337159942625;4455.064436468378;4479.529649386767;4481.305397663302;4550.226461501553;1767.6111255523765;1818.1727314072066;1861.3950466098302;1900.268396667293;1930.4980705016796;932.1098351074708;936.442816524167;956.8899576548786;994.835852084398;1042.1714736784274;16522.75215871763;16927.830721692873;17195.570110747332;17294.13529323554;17678.11609419524;934.8504239321812;944.4994153098869;980.4362491567377;986.7286496213455;1001.1640280365831;9900.108990190918;9983.151432128205;10003.499825008721;10014.298999069995;10047.196224302039;472093;651153;655317;658906;687537;67390.32609673939;68259;68444.31555684478;70246.64876675584;70254.2974570258;1043.3009883378154;1053.1153830782027;1056.4506456642503;1062.1184416933927;1070.3631339353071;14529.34617942174;14708.705825883208;14764.63088422798;14811.851881481478;15099.169545675093;34397.48403773995;34546.48180277348;34605.48091778676;34761.65238347584;35071.12322191965;44645;46241;46893;47293.29060064033;48709.756451217516;9480.431174129575;9690.95154524223;9860.852087218555;9890;9891.35706179096;11128.777424451304;11177.105831533296;11237.3257604544;14237.786433203302;17166.656666866842;13843.584692459242;14367.497137600134;14447.49433769813;14767;15104;14410.783838242645;14451.566453006426;14562;14610.561683149523;15566.766498502306;15113.017653852465;15411.066739305672;15514.456994005155;15534;15864.841351586798;9837.803243934937;9954.701358959242;10056.19550435978;10095.343802652804;10131.493425328557;10726.678199654023;14504.202268875313;14797.112173269637;14953.327100280296;15014.849851501345 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1567.7961864957554;1757.771489706338;1822.1344861190937;1823.0337920901927;1825.083662154738;7026.437884969203;7796.415268854837;8154.91845081549;8156.469829461081;8156.510609363442;7962.5620590867475;8059.476134051282;8112.756617301476;8117.1476994915565;8143.267105960463;2350.6944097267333;2379.214859096494;2390.497995420964;2394.31761947844;2396.18529699902;2339.5203983183405;2370.431096536836;2391.0316321889577;2392.7726865947793;2392.9880350598223;2333.7199536055614;2361.3860396296914;2364;2371.620540713486;2400.927972160838;2283.2008796920945;2300.2064287820676;2318.675385446039;2321.5124823787023;2341.672165896776;2290.576243394977;2305.988470057656;2312.861228326289;2314.36355002374;2315.340128063494;8035.6785728570785;8129.4715843469985;8210.671573137068;8218.383621228371;8224.218699223564;4759.571638552512;4814.6870453420415;4830.855074347775;4833.130036593412;4836.733979631119;3076.4769989101906;3104.5498402731705;3119.5788568543185;3144.371125774854;3151.77937544372;965.5317171171976;965.8647789309503;1001.244060734141;1017.323479885875;1025.943573103479;11137.498812553455;11166.776664466746;11167.888321116767;11192.216544841864;11194.216404851664;4311.590398912098;4320.65434765218;4366.039471316305;4387.254166791653;4387.714798538149;9520.047995200424;9627.326087173831;9633.036696330311;9634.421934683942;9641.42151470914;4916.655834091579;4927.655064145547;4928.852134435972;4929.876753081202;4930.087933732271;3834.827432765532;3949.6050394960275;4357.193919124972;4423.026934074498;4479.574440428154;3963.1875465530075;3973.3443981743267;3978.5822488638278;4011.117554138085;4023.275810354106;1227.8158276258632;1242.3912282981273;1244.5519612939336;1257.2016769351528;1278.9168704034207;1418.4042702065158;1419.198153043527;1423.7081398313321;1437.5543581489771;1452.5206681794982;7786.571738554434;7886.72396466121;7895.289423951757;7904.288614024766;7916.604169791487;477.59404506169477;489.56673344090734;495.2942057567951;496.42662724553196;497.1300224606938;6225.501959843202;6274.6235225885675;6301.14934483848;6336.081268216083;6355.3644635536075;9557.044295570387;9660.468674222986;9716.61133554664;9884.25868059884;9890.901090989142;9505.762355941019;9621.951890240503;9750.26872984529;9764.121229089416;9776.657816976372;7589;7750.689972401041;7755.689772409153;7757.495762775401;7757.92242077572;7481.17707052213;7551.018367612175;7559.357454616199;7567.621618919142;7691.038620172479;8660.09069047741;8726.389152759246;8815.382923195315;8851.557422128997;8964.193222609736;8850.955745221232;8991.055939126298;9059.864102038606;9060.818783624423;9257.907420925705;6010.759569617167;6451.451626611791;7055.611941343275;7364.447666424932;7397.149327827154;7942.722004729807;7945.721899733481;7948.0859701132795;7987.480813747088;7987.600619969095;7331.303526165006;7498.250174982458;7513.173550909286;7575.734849280248;7600.885986710093;7327.74352897646;7398.445116616168;7419.962900185465;7492.550446973199;7514.361279291322;7284.053073100463;7290.6719197635175;7322.267773222635;7326;7345.77962661121;6771.593704377753;6789.083473730983;6820.522563420513;6830.59016459014;6868.519203655696;6910.930890691029;6948;6958.965205173941;6960.37356637905;6966.616836074065 01:35:04 INFO - 2016-01-26 01:35:04,270 DEBUG : BROWSER_OUTPUT: |4;prototype.html;351.37630705497736;353.6693191865606;355.8985689078613;357.1375129063312;360.9765365251257;6163.568550201488;6187.38126187381;6190.597611155283;6203.596766210193;6210.09953556734;5839.211706419632;5888.057910734273;6011.5491338149595;6089.604175728575;6097.695115244231;4688.437387513503;4705.717656940586;4710.293455981596;4718.292256161569;4719.669623126382;4691.695039822418;4702.224133018048;4714.292856071583;4723.031778485402;4732.692374995631;4662.883427914296;4673.252279635259;4674.626029917598;4678.228092364773;4686.08621318843;4545.659075569329;4646.651501137429;4694.812207511695;4699.976500117512;4700.130475861976;4092.3043082676018;4092.3656833190903;4101.384792281152;4103.117829666606;4110.588941105895;5821.155932389822;5826.126081087828;5835.562332825013;5843.590948633598;5845.7369418376275;5349.251104845324;5453.263809385722;5455.1271796512765;5462.726863656841;5480.616356855022;6483.124778154983;6489.383508566679;6491.318411566765;6497.967510162466;6502;4528.728276303399;4531.252343363341;4532.0709254602725;4541.43232095988;4546.4544254689645;6720.6639668016405;6767.796966091074;6769;6772.796816095525;6795.626240556768;4566.292224705176;4672.6962747421985;4684.461286951976;4686.742229177428;4711.952880471152;4738.336632871401;4740.928886066712;4746.359241502353;4748.3114948332295;4749.121412538691;5462.0987537056735;5467.972660136674;5468.644538105011;5471.534919531805;5475.178723191473;6430;6579.440747536417;6587.604743715392;6589.967050164719;6590.4512439576665;1656.0643236571298;1668.357682292302;1670.6408122253774;1673.5899704572457;1676.3546034776455;1033.9379637221791;1091.3779145886767;1114.8104822180248;1142.8799776219353;1145.6963904565298;6221.782237621752;6282.340354262828;6285.465735412541;6298.622082675054;6309.337519560472;6654.966725166343;6656.467482601381;6657.434118099919;6658.334166583303;6658.7336506539195;6274.905876411857;6283.654399008098;6289.7484100636375;6320.5891617044745;6359.236891573041;3673;3709.350863598859;3710.0539362462937;3710.6103859094947;3711.3690672585726;6940.271271516519;6951.7914462566205;6954.9304506955295;6962.060121883481;6964.199117101497;6780.457563394916;6901.447884169254;6992.230854605989;7021.157461104598;7024.332688394697;6988.965055174794;7053.858922821515;7057.752978645723;7058.329458701415;7063.434925205971;6852.451803855779;6855.520113592001;6859.245482996766;6862.176538815374;6862.416694581017;6586.967065164644;6765.797026089225;6775.356341147583;6777.2883847195335;6783.93216067833;7033.683484243324;7034.331738484836;7038.3665470107935;7046.365827075588;7048.471364647569;4619.884502887455;4654.674172808006;4656.5110663379855;4658.277966915133;4659.860204193879;6703;6851.383375496229;6851.725930962905;6854.554453960676;6863.931360686529;6638.369354911276;6648.667566621552;6649.235337936006;6658.500612454182;6662.666866656745;6588.7364505421165;6617.470602351897;6622.139121914121;6630.9336906630315;6640.601563906181;6260.937390626218;6402.231732192166;6450.225972883284;6533.640649764308;6539.280679125388;6476.125723027331;6557.508186886098;6658.201015877932;6681.198256209286;6693.531452798257;6510.576812507172;6513.2835388108215;6517.608943463408;6521.152250207443;6525.1517302750335 01:35:04 INFO - 2016-01-26 01:35:04,271 DEBUG : BROWSER_OUTPUT: |5;yui.html;811.6144831205178;830.5473516933271;894.9373543851929;898.2185498616205;938.948357840319;13491.123077;13736.107153035045;13742.381592828335;13742.656433589165;13756.58730238092;13439.26084065376;13580.524681636145;13591;13596.660083497918;13615.591532254024;736.9373603243731;743.9442041846856;893.8033632600834;901.8917729872425;905.302916754099;870.5212133326677;878.4860856399014;881.2377293640986;882.2985726347537;887.9067697891725;783.6982761636754;944.4380593546863;950.1686024728363;950.3917492804611;959.2469911119772;781.9257170568787;919.1406035356963;919.6827094652334;921.2675922641482;922.2667978956746;681.0397339750976;681.3016657925616;681.4139839737821;685.8353995041155;691.1464341538197;13457.058005939589;13615.659608509768;13650.044496885223;13762;13778.931105344509;11989.280643161439;12875.420606072656;13057.804132938014;13105.475780968749;13149.21104733719;10424.68725938223;10734.248602597821;10788.190885683527;10791.136709063334;10814.675559733221;3586.1751797086554;3632.8183590820354;3649.051246675857;3652.8904132876055;3733.197362567061;45067.54932450699;45222.09555808865;45305;45528.77235613834;45589.54410455886;10172.237082218864;10199.28604997658;10314.226433017553;10404.843927341097;10489.842652360221;18645.254189832256;18786.624267514573;18790.906045469685;18960.24159033623;18989.33537326187;7980.3615710743;8013.358931285483;8098.838023239502;8108.5540295283145;8123.18768123183;4452.933206001848;4458.353538736865;4516.36770852081;4652.534746525321;4662.32396302541;3118.875244990175;3147.0244224290545;3147.8268695221523;3151.889683861054;3153.731932785693;554.7254109215884;575.1746244139589;581.8545363659085;587.4683411512588;592.1443514122052;561.6854561445604;680.0547239337325;686.2931180883697;713.1620346093423;721.8195451137216;8388.664453421918;8532.233212386327;8584.699535516227;8605.827883442296;8789.208971192624;660.4419265720459;668.6990854115668;693.1855070292386;693.928623060165;713.9643017849087;3838.692904567628;3969.3649016157856;4325.178216138924;4404.185225733249;4435.844745433958;54441.45558544364;55301;55642.443575563724;55805.16292255619;55961.16058259129;48674.756626216644;53675.46324536783;53793.1931021035;54521.1821822673;54616.45383546193;45805.77097114493;45853.31220031702;46057.30914036292;47314.763426182646;47644.76177619159;9157.679481218125;9267.44395336255;9321.673741419017;9341.159295663423;9535.618575256913;34665.306693865474;35405;35772.4634130483;35962.64037359593;36124.4581331275;44036;44095;44105;44325.77837110794;45006.77496612496;11243.425920370992;11626.186166968231;11708.88291117078;11780.175387722777;12183.75632487363;12074.27554346742;12439.378031098227;12907.935460322638;13127.146735462164;13529.255890926093;10446.843297350393;10651.57393704243;11666.125040621817;11921.761564768483;12411.875881241072;11654.067674585844;12028.518859245532;12081.939590301992;12086;12287.631371058882;9119.971281792672;10079.193664506674;11193.832092518782;11442.084633229488;11792.174547781917;10163.441010744184;10175.79648407013;10291.073254683042;10332.276740628084;10539.894601053891;9774.169195618451;10141.84787228207;10217.59129634836;10244.590216391261;10248.692539223835 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,844 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: __startTimestamp1453800904265__endTimestamp 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: Number of tests: 6 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5605.96 Median:5830.91 stddev:621.83 (10.7%) stddev-sans-first:597.27 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: Values: 3318.0 3374.8 3405.7 3407.2 3425.2 5547.9 5598.7 5599.1 5613.0 5613.7 5628.1 5636.3 5637.3 5641.7 5646.3 4978.9 5003.4 5090.9 5121.1 5184.7 5050.4 5131.5 5150.5 5155.7 5162.4 5089.8 5155.3 5194.4 5210.6 5215.4 5188.0 5205.5 5206.9 5213.5 5246.5 4910.1 4957.4 4999.6 5090.6 5092.7 5392.2 5399.1 5402.3 5403.8 5417.5 5950.8 5968.8 6000.8 6009.1 6016.5 5849.9 5875.4 5974.1 6033.7 6047.4 5617.9 5627.6 5629.1 5632.2 5634.6 6089.8 6106.6 6110.7 6118.0 6120.1 5733.3 5842.6 5848.5 5956.0 6003.0 6070.3 6073.5 6076.6 6079.0 6080.2 5864.3 5999.5 6048.4 6051.2 6052.6 5882.2 5948.7 5962.4 5966.8 6046.8 4638.7 4661.0 4675.1 4676.6 4705.6 4049.6 4129.3 4147.5 4198.5 4243.8 5603.7 5666.1 5671.6 5680.5 5709.6 6158.1 6188.3 6191.9 6197.4 6198.6 5581.6 5634.0 5692.1 5845.4 5849.8 5319.9 5342.8 5343.3 5351.5 5370.6 6309.6 6325.6 6326.2 6328.2 6363.8 5799.4 5930.4 5994.4 6140.2 6169.2 6260.7 6274.2 6278.6 6279.0 6287.9 5993.4 6043.8 6045.6 6064.8 6068.6 5969.0 6010.1 6113.7 6124.6 6132.5 5944.4 5947.6 5954.2 5956.0 5962.5 5667.6 5922.2 5926.6 5926.6 5932.1 5679.1 5722.3 5753.4 5758.3 5761.8 5896.4 5930.4 5950.1 5955.9 5966.9 5653.0 5830.9 5836.4 5839.2 5840.3 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,272 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:55481.15 Median:35795.91 stddev:100027.82 (279.4%) stddev-sans-first:100223.48 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: Values: 579.8 619.8 626.0 626.7 628.5 31740.0 32514.2 32558.3 32580.3 32595.2 41474.4 42339.0 42448.0 42501.4 42503.0 9068.2 9171.9 9209.3 9217.1 9217.4 8145.2 8368.7 9048.2 9157.5 10946.7 10824.8 10915.6 10919.9 10945.9 10950.4 10833.8 10938.7 10949.1 10950.9 10953.7 10665.5 10750.4 10773.5 10787.7 10798.1 43790.8 44103.6 44161.1 44165.0 44217.8 3566.1 3695.3 3748.7 3799.8 4053.5 32627.3 33037.3 33456.2 33515.5 33591.3 4178.4 4243.4 4247.6 4258.8 4318.6 302886.0 309495.0 310077.0 310094.0 310818.0 58438.7 59395.4 59513.4 71141.6 72459.0 69587.0 70232.0 70578.0 70586.6 70640.6 18302.2 18471.6 18517.6 18519.6 18524.0 13948.3 14117.4 14122.7 14254.8 14256.1 23498.3 23612.9 23641.0 23745.6 23767.6 8850.6 8982.8 8987.5 9006.4 9058.6 1014.3 1047.7 1047.7 1079.8 1212.7 30371.0 31527.2 34470.3 34779.0 35415.3 1055.4 1061.3 1145.0 1162.2 1182.4 14464.7 15520.7 15903.8 17340.6 18160.6 482495.0 483205.0 485202.0 489578.0 490021.0 61498.0 62049.7 62177.7 62178.0 62232.4 1349.8 1411.4 1448.2 1454.7 1480.2 31837.0 32435.0 32521.7 32526.7 32647.3 51832.5 52592.7 52640.5 52755.2 52890.5 312262.0 316565.0 317551.0 319983.0 320367.0 35348.0 35757.0 35834.8 35927.8 36128.8 46353.8 47098.8 47211.8 47329.3 47874.0 39176.4 39573.8 39674.4 45257.3 45537.5 41978.0 42142.6 42227.2 42833.4 43049.8 41044.0 41696.6 41913.0 41980.0 42015.8 35741.8 35756.5 35899.6 36773.0 36989.3 28258.5 28482.9 28553.0 28578.0 28714.0 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31011.15 Median:2450.77 stddev:102714.33 (4191.1%) stddev-sans-first:102976.73 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: Values: 388.0 388.6 389.7 390.3 391.6 15002.4 16748.2 16880.8 17858.1 17903.5 16921.4 17238.6 17253.6 18395.3 18657.7 5015.8 5116.2 5119.5 5131.9 5139.2 3950.4 4072.7 4075.3 5065.7 5427.4 5591.8 5645.5 5657.2 5662.6 5671.8 5149.3 5323.6 5324.0 5324.2 5330.5 5102.5 5110.1 5122.9 5296.4 5347.4 13100.6 13347.5 13354.5 18359.9 21110.2 2047.6 2198.1 2321.0 2426.1 2475.4 9931.1 10021.3 10140.9 10159.5 13225.1 1778.9 1782.2 1822.8 1831.3 1844.1 60437.0 64354.7 64417.7 64504.4 64548.4 11086.2 11096.6 11127.8 11135.6 11165.6 14667.9 14770.1 14783.7 14784.2 14810.7 8666.6 8676.8 8686.2 8735.1 8769.1 8652.8 8689.3 8700.0 8781.0 8794.8 4418.9 4455.1 4479.5 4481.3 4550.2 1767.6 1818.2 1861.4 1900.3 1930.5 932.1 936.4 956.9 994.8 1042.2 16522.8 16927.8 17195.6 17294.1 17678.1 934.9 944.5 980.4 986.7 1001.2 9900.1 9983.2 10003.5 10014.3 10047.2 472093.0 651153.0 655317.0 658906.0 687537.0 67390.3 68259.0 68444.3 70246.6 70254.3 1043.3 1053.1 1056.5 1062.1 1070.4 14529.3 14708.7 14764.6 14811.9 15099.2 34397.5 34546.5 34605.5 34761.7 35071.1 44645.0 46241.0 46893.0 47293.3 48709.8 9480.4 9691.0 9860.9 9890.0 9891.4 11128.8 11177.1 11237.3 14237.8 17166.7 13843.6 14367.5 14447.5 14767.0 15104.0 14410.8 14451.6 14562.0 14610.6 15566.8 15113.0 15411.1 15514.5 15534.0 15864.8 9837.8 9954.7 10056.2 10095.3 10131.5 10726.7 14504.2 14797.1 14953.3 15014.8 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5709.56 Median:6780.34 stddev:2990.11 (44.1%) stddev-sans-first:2982.30 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: Values: 1567.8 1757.8 1822.1 1823.0 1825.1 7026.4 7796.4 8154.9 8156.5 8156.5 7962.6 8059.5 8112.8 8117.1 8143.3 2350.7 2379.2 2390.5 2394.3 2396.2 2339.5 2370.4 2391.0 2392.8 2393.0 2333.7 2361.4 2364.0 2371.6 2400.9 2283.2 2300.2 2318.7 2321.5 2341.7 2290.6 2306.0 2312.9 2314.4 2315.3 8035.7 8129.5 8210.7 8218.4 8224.2 4759.6 4814.7 4830.9 4833.1 4836.7 3076.5 3104.5 3119.6 3144.4 3151.8 965.5 965.9 1001.2 1017.3 1025.9 11137.5 11166.8 11167.9 11192.2 11194.2 4311.6 4320.7 4366.0 4387.3 4387.7 9520.0 9627.3 9633.0 9634.4 9641.4 4916.7 4927.7 4928.9 4929.9 4930.1 3834.8 3949.6 4357.2 4423.0 4479.6 3963.2 3973.3 3978.6 4011.1 4023.3 1227.8 1242.4 1244.6 1257.2 1278.9 1418.4 1419.2 1423.7 1437.6 1452.5 7786.6 7886.7 7895.3 7904.3 7916.6 477.6 489.6 495.3 496.4 497.1 6225.5 6274.6 6301.1 6336.1 6355.4 9557.0 9660.5 9716.6 9884.3 9890.9 9505.8 9622.0 9750.3 9764.1 9776.7 7589.0 7750.7 7755.7 7757.5 7757.9 7481.2 7551.0 7559.4 7567.6 7691.0 8660.1 8726.4 8815.4 8851.6 8964.2 8851.0 8991.1 9059.9 9060.8 9257.9 6010.8 6451.5 7055.6 7364.4 7397.1 7942.7 7945.7 7948.1 7987.5 7987.6 7331.3 7498.3 7513.2 7575.7 7600.9 7327.7 7398.4 7420.0 7492.6 7514.4 7284.1 7290.7 7322.3 7326.0 7345.8 6771.6 6789.1 6820.5 6830.6 6868.5 6910.9 6948.0 6959.0 6960.4 6966.6 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,274 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5486.16 Median:6241.36 stddev:1656.25 (26.5%) stddev-sans-first:1615.43 01:35:04 INFO - 2016-01-26 01:35:04,276 DEBUG : BROWSER_OUTPUT: Values: 351.4 353.7 355.9 357.1 361.0 6163.6 6187.4 6190.6 6203.6 6210.1 5839.2 5888.1 6011.5 6089.6 6097.7 4688.4 4705.7 4710.3 4718.3 4719.7 4691.7 4702.2 4714.3 4723.0 4732.7 4662.9 4673.3 4674.6 4678.2 4686.1 4545.7 4646.7 4694.8 4700.0 4700.1 4092.3 4092.4 4101.4 4103.1 4110.6 5821.2 5826.1 5835.6 5843.6 5845.7 5349.3 5453.3 5455.1 5462.7 5480.6 6483.1 6489.4 6491.3 6498.0 6502.0 4528.7 4531.3 4532.1 4541.4 4546.5 6720.7 6767.8 6769.0 6772.8 6795.6 4566.3 4672.7 4684.5 4686.7 4712.0 4738.3 4740.9 4746.4 4748.3 4749.1 5462.1 5468.0 5468.6 5471.5 5475.2 6430.0 6579.4 6587.6 6590.0 6590.5 1656.1 1668.4 1670.6 1673.6 1676.4 1033.9 1091.4 1114.8 1142.9 1145.7 6221.8 6282.3 6285.5 6298.6 6309.3 6655.0 6656.5 6657.4 6658.3 6658.7 6274.9 6283.7 6289.7 6320.6 6359.2 3673.0 3709.4 3710.1 3710.6 3711.4 6940.3 6951.8 6954.9 6962.1 6964.2 6780.5 6901.4 6992.2 7021.2 7024.3 6989.0 7053.9 7057.8 7058.3 7063.4 6852.5 6855.5 6859.2 6862.2 6862.4 6587.0 6765.8 6775.4 6777.3 6783.9 7033.7 7034.3 7038.4 7046.4 7048.5 4619.9 4654.7 4656.5 4658.3 4659.9 6703.0 6851.4 6851.7 6854.6 6863.9 6638.4 6648.7 6649.2 6658.5 6662.7 6588.7 6617.5 6622.1 6630.9 6640.6 6260.9 6402.2 6450.2 6533.6 6539.3 6476.1 6557.5 6658.2 6681.2 6693.5 6510.6 6513.3 6517.6 6521.2 6525.2 01:35:04 INFO - 2016-01-26 01:35:04,276 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,276 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13931.24 Median:24380.81 stddev:15724.69 (64.5%) stddev-sans-first:15737.94 01:35:04 INFO - 2016-01-26 01:35:04,276 DEBUG : BROWSER_OUTPUT: Values: 811.6 830.5 894.9 898.2 938.9 13491.1 13736.1 13742.4 13742.7 13756.6 13439.3 13580.5 13591.0 13596.7 13615.6 736.9 743.9 893.8 901.9 905.3 870.5 878.5 881.2 882.3 887.9 783.7 944.4 950.2 950.4 959.2 781.9 919.1 919.7 921.3 922.3 681.0 681.3 681.4 685.8 691.1 13457.1 13615.7 13650.0 13762.0 13778.9 11989.3 12875.4 13057.8 13105.5 13149.2 10424.7 10734.2 10788.2 10791.1 10814.7 3586.2 3632.8 3649.1 3652.9 3733.2 45067.5 45222.1 45305.0 45528.8 45589.5 10172.2 10199.3 10314.2 10404.8 10489.8 18645.3 18786.6 18790.9 18960.2 18989.3 7980.4 8013.4 8098.8 8108.6 8123.2 4452.9 4458.4 4516.4 4652.5 4662.3 3118.9 3147.0 3147.8 3151.9 3153.7 554.7 575.2 581.9 587.5 592.1 561.7 680.1 686.3 713.2 721.8 8388.7 8532.2 8584.7 8605.8 8789.2 660.4 668.7 693.2 693.9 714.0 3838.7 3969.4 4325.2 4404.2 4435.8 54441.5 55301.0 55642.4 55805.2 55961.2 48674.8 53675.5 53793.2 54521.2 54616.5 45805.8 45853.3 46057.3 47314.8 47644.8 9157.7 9267.4 9321.7 9341.2 9535.6 34665.3 35405.0 35772.5 35962.6 36124.5 44036.0 44095.0 44105.0 44325.8 45006.8 11243.4 11626.2 11708.9 11780.2 12183.8 12074.3 12439.4 12907.9 13127.1 13529.3 10446.8 10651.6 11666.1 11921.8 12411.9 11654.1 12028.5 12081.9 12086.0 12287.6 9120.0 10079.2 11193.8 11442.1 11792.2 10163.4 10175.8 10291.1 10332.3 10539.9 9774.2 10141.8 10217.6 10244.6 10248.7 01:35:04 INFO - 2016-01-26 01:35:04,276 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:35:04 INFO - 2016-01-26 01:35:04,276 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2016-01-26 01:35:04,784 INFO : Browser exited with error code: 0 01:35:04 INFO - 2016-01-26 01:35:04,800 INFO : Completed test dromaeo_css (00:18:30) 01:35:04 INFO - 2016-01-26 01:35:04,800 INFO : Starting test kraken 01:35:04 INFO - 2016-01-26 01:35:04,800 DEBUG : operating with platform_type : linux_ 01:35:04 INFO - 2016-01-26 01:35:04,800 INFO : Initialising browser for kraken test... 01:35:04 INFO - 2016-01-26 01:35:04,806 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppICTo8/profile http://localhost:53031/getInfo.html 01:35:08 INFO - 2016-01-26 01:35:08,837 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:35:08 INFO - 2016-01-26 01:35:08,838 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:35:08 INFO - 2016-01-26 01:35:08,838 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:35:08 INFO - 2016-01-26 01:35:08,838 DEBUG : BROWSER_OUTPUT: __metrics 01:35:09 INFO - 2016-01-26 01:35:09,148 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:35:10 INFO - 2016-01-26 01:35:10,025 INFO : Browser initialized. 01:35:10 INFO - 2016-01-26 01:35:10,026 INFO : Running cycle 1/1 for kraken test... 01:35:10 INFO - 2016-01-26 01:35:10,026 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppICTo8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:35:21 INFO - 2016-01-26 01:35:21,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 134336512 01:35:21 INFO - 2016-01-26 01:35:21,807 DEBUG : BROWSER_OUTPUT: 01:35:24 INFO - 2016-01-26 01:35:24,234 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53031/tests/kraken/driver.html?testName=audio-beat-detection) 01:35:24 INFO - 2016-01-26 01:35:24,802 DEBUG : BROWSER_OUTPUT: RSS: Main: 167112704 01:35:24 INFO - 2016-01-26 01:35:24,802 DEBUG : BROWSER_OUTPUT: 01:35:27 INFO - 2016-01-26 01:35:27,371 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53031/tests/kraken/driver.html?testName=audio-dft) 01:35:27 INFO - 2016-01-26 01:35:27,932 DEBUG : BROWSER_OUTPUT: RSS: Main: 165044224 01:35:27 INFO - 2016-01-26 01:35:27,932 DEBUG : BROWSER_OUTPUT: 01:35:30 INFO - 2016-01-26 01:35:30,750 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53031/tests/kraken/driver.html?testName=audio-fft) 01:35:31 INFO - 2016-01-26 01:35:31,312 DEBUG : BROWSER_OUTPUT: RSS: Main: 160542720 01:35:31 INFO - 2016-01-26 01:35:31,312 DEBUG : BROWSER_OUTPUT: 01:35:33 INFO - 2016-01-26 01:35:33,264 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53031/tests/kraken/driver.html?testName=audio-oscillator) 01:35:33 INFO - 2016-01-26 01:35:33,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 162385920 01:35:33 INFO - 2016-01-26 01:35:33,825 DEBUG : BROWSER_OUTPUT: 01:35:35 INFO - 2016-01-26 01:35:35,998 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53031/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:35:36 INFO - 2016-01-26 01:35:36,565 DEBUG : BROWSER_OUTPUT: RSS: Main: 160186368 01:35:36 INFO - 2016-01-26 01:35:36,566 DEBUG : BROWSER_OUTPUT: 01:35:39 INFO - 2016-01-26 01:35:39,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53031/tests/kraken/driver.html?testName=imaging-darkroom) 01:35:39 INFO - 2016-01-26 01:35:39,964 DEBUG : BROWSER_OUTPUT: RSS: Main: 159674368 01:35:39 INFO - 2016-01-26 01:35:39,964 DEBUG : BROWSER_OUTPUT: 01:35:43 INFO - 2016-01-26 01:35:43,311 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53031/tests/kraken/driver.html?testName=imaging-desaturate) 01:35:43 INFO - 2016-01-26 01:35:43,877 DEBUG : BROWSER_OUTPUT: RSS: Main: 159821824 01:35:43 INFO - 2016-01-26 01:35:43,877 DEBUG : BROWSER_OUTPUT: 01:35:46 INFO - 2016-01-26 01:35:46,563 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53031/tests/kraken/driver.html?testName=json-parse-financial) 01:35:47 INFO - 2016-01-26 01:35:47,137 DEBUG : BROWSER_OUTPUT: RSS: Main: 161042432 01:35:47 INFO - 2016-01-26 01:35:47,137 DEBUG : BROWSER_OUTPUT: 01:35:48 INFO - 2016-01-26 01:35:48,665 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53031/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:35:49 INFO - 2016-01-26 01:35:49,231 DEBUG : BROWSER_OUTPUT: RSS: Main: 167723008 01:35:49 INFO - 2016-01-26 01:35:49,232 DEBUG : BROWSER_OUTPUT: 01:35:50 INFO - 2016-01-26 01:35:50,962 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:35:51 INFO - 2016-01-26 01:35:51,552 DEBUG : BROWSER_OUTPUT: RSS: Main: 164270080 01:35:51 INFO - 2016-01-26 01:35:51,552 DEBUG : BROWSER_OUTPUT: 01:35:53 INFO - 2016-01-26 01:35:53,557 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:35:54 INFO - 2016-01-26 01:35:54,130 DEBUG : BROWSER_OUTPUT: RSS: Main: 169119744 01:35:54 INFO - 2016-01-26 01:35:54,131 DEBUG : BROWSER_OUTPUT: 01:35:56 INFO - 2016-01-26 01:35:56,489 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:35:57 INFO - 2016-01-26 01:35:57,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 174383104 01:35:57 INFO - 2016-01-26 01:35:57,070 DEBUG : BROWSER_OUTPUT: 01:35:59 INFO - 2016-01-26 01:35:59,606 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:36:00 INFO - 2016-01-26 01:36:00,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 173649920 01:36:00 INFO - 2016-01-26 01:36:00,167 DEBUG : BROWSER_OUTPUT: 01:36:01 INFO - 2016-01-26 01:36:01,837 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53031/tests/kraken/driver.html?testName=ai-astar) 01:36:02 INFO - 2016-01-26 01:36:02,083 DEBUG : BROWSER_OUTPUT: RSS: Main: 231370752 01:36:02 INFO - 2016-01-26 01:36:02,083 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,084 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:36:02 INFO - 2016-01-26 01:36:02,085 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:36:02 INFO - 2016-01-26 01:36:02,085 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:36:02 INFO - 2016-01-26 01:36:02,085 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:36:02 INFO - 2016-01-26 01:36:02,085 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;103;111;108;109;97;110;109;110 01:36:02 INFO - 2016-01-26 01:36:02,086 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;122;149;167;125;143;154;138;156;128 01:36:02 INFO - 2016-01-26 01:36:02,086 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;163;162;161;162;161;162;167;161;160 01:36:02 INFO - 2016-01-26 01:36:02,086 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;71;145;71;72;72;71;153;74 01:36:02 INFO - 2016-01-26 01:36:02,086 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;136;92;92;92;143;91;92;138 01:36:02 INFO - 2016-01-26 01:36:02,086 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;110;109;109;109;110;109;109 01:36:02 INFO - 2016-01-26 01:36:02,086 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;145;147;144;147;146;146;144;147;147 01:36:02 INFO - 2016-01-26 01:36:02,087 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;94;95;101;95;95 01:36:02 INFO - 2016-01-26 01:36:02,088 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;57;58;56;56;58;56;55;55 01:36:02 INFO - 2016-01-26 01:36:02,088 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;63;64;64;65;64;63;64;64;64 01:36:02 INFO - 2016-01-26 01:36:02,088 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;90;81;80;81;81;81;83;82;82 01:36:02 INFO - 2016-01-26 01:36:02,088 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;119;117;111;119;120;117;119;120;121 01:36:02 INFO - 2016-01-26 01:36:02,088 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;141;146;147;152;137;157;157;141;143 01:36:02 INFO - 2016-01-26 01:36:02,088 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;63;64;64;65;65;64;65;68;64 01:36:02 INFO - 2016-01-26 01:36:02,089 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:36:02 INFO - 2016-01-26 01:36:02,089 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:36:02 INFO - 2016-01-26 01:36:02,089 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,886 01:36:02 INFO - 2016-01-26 01:36:02,089 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:36:02 INFO - 2016-01-26 01:36:02,089 DEBUG : BROWSER_OUTPUT: __startTimestamp1453800962084__endTimestamp 01:36:02 INFO - 2016-01-26 01:36:02,089 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: Number of tests: 14 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.50 Median:110.00 stddev:4.30 (3.9%) stddev-sans-first:4.56 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 103.0 111.0 108.0 109.0 97.0 110.0 109.0 110.0 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.80 Median:151.50 stddev:15.20 (10.0%) stddev-sans-first:15.47 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: Values: 156.0 122.0 149.0 167.0 125.0 143.0 154.0 138.0 156.0 128.0 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.94 (1.2%) stddev-sans-first:2.03 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: Values: 161.0 163.0 162.0 161.0 162.0 161.0 162.0 167.0 161.0 160.0 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,093 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.00 Median:71.00 stddev:32.75 (46.1%) stddev-sans-first:34.15 01:36:02 INFO - 2016-01-26 01:36:02,094 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 71.0 145.0 71.0 72.0 72.0 71.0 153.0 74.0 01:36:02 INFO - 2016-01-26 01:36:02,094 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,094 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:92.00 stddev:22.84 (24.8%) stddev-sans-first:23.65 01:36:02 INFO - 2016-01-26 01:36:02,094 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 136.0 92.0 92.0 92.0 143.0 91.0 92.0 138.0 01:36:02 INFO - 2016-01-26 01:36:02,094 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,094 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 01:36:02 INFO - 2016-01-26 01:36:02,094 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 110.0 109.0 109.0 109.0 110.0 109.0 109.0 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.00 Median:147.00 stddev:1.25 (0.8%) stddev-sans-first:1.27 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: Values: 147.0 145.0 147.0 144.0 147.0 146.0 146.0 144.0 147.0 147.0 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:1.96 (2.1%) stddev-sans-first:2.07 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 101.0 95.0 95.0 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.00 stddev:1.06 (1.9%) stddev-sans-first:1.12 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 57.0 58.0 56.0 56.0 58.0 56.0 55.0 55.0 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: Values: 65.0 63.0 64.0 64.0 65.0 64.0 63.0 64.0 64.0 64.0 01:36:02 INFO - 2016-01-26 01:36:02,095 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:81.50 stddev:2.86 (3.5%) stddev-sans-first:3.00 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: Values: 81.0 90.0 81.0 80.0 81.0 81.0 81.0 83.0 82.0 82.0 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:119.50 stddev:2.95 (2.5%) stddev-sans-first:2.98 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: Values: 121.0 119.0 117.0 111.0 119.0 120.0 117.0 119.0 120.0 121.0 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.80 Median:147.00 stddev:6.78 (4.6%) stddev-sans-first:7.19 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: Values: 147.0 141.0 146.0 147.0 152.0 137.0 157.0 157.0 141.0 143.0 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.50 stddev:1.35 (2.1%) stddev-sans-first:1.41 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 64.0 64.0 65.0 65.0 64.0 65.0 68.0 64.0 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:36:02 INFO - 2016-01-26 01:36:02,096 DEBUG : BROWSER_OUTPUT: 01:36:02 INFO - 2016-01-26 01:36:02,772 INFO : Browser exited with error code: 0 01:36:02 INFO - 2016-01-26 01:36:02,825 INFO : Completed test kraken (00:00:58) 01:36:02 INFO - 2016-01-26 01:36:02,825 INFO : Starting test v8_7 01:36:02 INFO - 2016-01-26 01:36:02,825 DEBUG : operating with platform_type : linux_ 01:36:02 INFO - 2016-01-26 01:36:02,826 INFO : Initialising browser for v8_7 test... 01:36:02 INFO - 2016-01-26 01:36:02,832 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpukkofg/profile http://localhost:53031/getInfo.html 01:36:06 INFO - 2016-01-26 01:36:06,933 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:36:06 INFO - 2016-01-26 01:36:06,934 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:36:06 INFO - 2016-01-26 01:36:06,934 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:36:06 INFO - 2016-01-26 01:36:06,934 DEBUG : BROWSER_OUTPUT: __metrics 01:36:07 INFO - 2016-01-26 01:36:07,095 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:36:08 INFO - 2016-01-26 01:36:08,101 INFO : Browser initialized. 01:36:08 INFO - 2016-01-26 01:36:08,101 INFO : Running cycle 1/1 for v8_7 test... 01:36:08 INFO - 2016-01-26 01:36:08,101 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpukkofg/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:36:19 INFO - 2016-01-26 01:36:19,906 DEBUG : BROWSER_OUTPUT: RSS: Main: 134549504 01:36:19 INFO - 2016-01-26 01:36:19,906 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53031/tests/v8_7/run.html (next: http://localhost:53031/tests/v8_7/run.html) 01:36:41 INFO - 2016-01-26 01:36:41,533 DEBUG : BROWSER_OUTPUT: RSS: Main: 423006208 01:36:41 INFO - 2016-01-26 01:36:41,533 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,534 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:36:41 INFO - 2016-01-26 01:36:41,535 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:36:41 INFO - 2016-01-26 01:36:41,535 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:36:41 INFO - 2016-01-26 01:36:41,535 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:36:41 INFO - 2016-01-26 01:36:41,535 DEBUG : BROWSER_OUTPUT: |0;Richards;168.54879487611663 01:36:41 INFO - 2016-01-26 01:36:41,535 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;167.7289500167729 01:36:41 INFO - 2016-01-26 01:36:41,535 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.70680044593087 01:36:41 INFO - 2016-01-26 01:36:41,536 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5128.205128205128 01:36:41 INFO - 2016-01-26 01:36:41,536 DEBUG : BROWSER_OUTPUT: |4;RayTrace;940.7337723424271 01:36:41 INFO - 2016-01-26 01:36:41,536 DEBUG : BROWSER_OUTPUT: |5;Earley;542.8881650380022 01:36:41 INFO - 2016-01-26 01:36:41,536 DEBUG : BROWSER_OUTPUT: |6;Boyer;10547.368421052632 01:36:41 INFO - 2016-01-26 01:36:41,536 DEBUG : BROWSER_OUTPUT: |7;RegExp;26256.410256410258 01:36:41 INFO - 2016-01-26 01:36:41,537 DEBUG : BROWSER_OUTPUT: |8;Splay;530.2226935312831 01:36:41 INFO - 2016-01-26 01:36:41,537 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 01:36:41 INFO - 2016-01-26 01:36:41,537 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:36:41 INFO - 2016-01-26 01:36:41,537 DEBUG : BROWSER_OUTPUT: __startTimestamp1453801001533__endTimestamp 01:36:41 INFO - 2016-01-26 01:36:41,537 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,538 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:36:41 INFO - 2016-01-26 01:36:41,538 DEBUG : BROWSER_OUTPUT: Number of tests: 10 01:36:41 INFO - 2016-01-26 01:36:41,538 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,538 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.55 Median:168.55 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,538 DEBUG : BROWSER_OUTPUT: Values: 168.5 01:36:41 INFO - 2016-01-26 01:36:41,538 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,539 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:167.73 Median:167.73 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,539 DEBUG : BROWSER_OUTPUT: Values: 167.7 01:36:41 INFO - 2016-01-26 01:36:41,539 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,539 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.71 Median:278.71 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,539 DEBUG : BROWSER_OUTPUT: Values: 278.7 01:36:41 INFO - 2016-01-26 01:36:41,539 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,540 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5128.21 Median:5128.21 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,540 DEBUG : BROWSER_OUTPUT: Values: 5128.2 01:36:41 INFO - 2016-01-26 01:36:41,540 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,540 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:940.73 Median:940.73 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,540 DEBUG : BROWSER_OUTPUT: Values: 940.7 01:36:41 INFO - 2016-01-26 01:36:41,540 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,542 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.89 Median:542.89 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,542 DEBUG : BROWSER_OUTPUT: Values: 542.9 01:36:41 INFO - 2016-01-26 01:36:41,542 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,542 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10547.37 Median:10547.37 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,542 DEBUG : BROWSER_OUTPUT: Values: 10547.4 01:36:41 INFO - 2016-01-26 01:36:41,542 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,543 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26256.41 Median:26256.41 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,543 DEBUG : BROWSER_OUTPUT: Values: 26256.4 01:36:41 INFO - 2016-01-26 01:36:41,543 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,543 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:530.22 Median:530.22 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,543 DEBUG : BROWSER_OUTPUT: Values: 530.2 01:36:41 INFO - 2016-01-26 01:36:41,543 DEBUG : BROWSER_OUTPUT: 01:36:41 INFO - 2016-01-26 01:36:41,543 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 01:36:41 INFO - 2016-01-26 01:36:41,544 DEBUG : BROWSER_OUTPUT: Values: 4822.1 01:36:41 INFO - 2016-01-26 01:36:41,544 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:36:41 INFO - 2016-01-26 01:36:41,544 DEBUG : BROWSER_OUTPUT: 01:36:42 INFO - 2016-01-26 01:36:42,216 INFO : Browser exited with error code: 0 01:36:42 INFO - 2016-01-26 01:36:42,233 INFO : Completed test v8_7 (00:00:39) 01:36:42 INFO - 2016-01-26 01:36:42,534 INFO : Completed test suite (00:20:08) 01:36:42 INFO - 2016-01-26 01:36:42,534 DEBUG : Working with test: dromaeo_css 01:36:42 INFO - 2016-01-26 01:36:42,535 DEBUG : Generating results file: dromaeo_css 01:36:42 INFO - 2016-01-26 01:36:42,536 DEBUG : Working with test: kraken 01:36:42 INFO - 2016-01-26 01:36:42,536 DEBUG : Generating results file: kraken 01:36:42 INFO - 2016-01-26 01:36:42,536 INFO : javascript benchmark 01:36:42 INFO - 2016-01-26 01:36:42,536 DEBUG : Working with test: v8_7 01:36:42 INFO - 2016-01-26 01:36:42,536 DEBUG : Generating results file: v8_7 01:36:42 INFO - 2016-01-26 01:36:42,537 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:36:42 INFO - 2016-01-26 01:36:42,584 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 01:36:42 INFO - 2016-01-26 01:36:42,584 DEBUG : process_Request line: dromaeo_css 7330.62 graph.html#tests=[[72,53,35]] 01:36:42 INFO - 2016-01-26 01:36:42,584 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:36:42 INFO - 2016-01-26 01:36:42,628 DEBUG : process_Request line: kraken 1489.40 graph.html#tests=[[232,53,35]] 01:36:42 INFO - 2016-01-26 01:36:42,628 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:36:42 INFO - 2016-01-26 01:36:42,653 DEBUG : process_Request line: v8_7 23067.58 graph.html#tests=[[230,53,35]] 01:36:42 INFO - 2016-01-26 01:36:42,654 INFO : javascript benchmark 01:36:42 INFO - 2016-01-26 01:36:42,658 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3318.0377690469763, 3374.8312584370774, 3405.7105146062568, 3407.1652445150944, 3425.1950791563977, 5547.916781248275, 5598.7200639968, 5599.1041433370665, 5612.971935140324, 5613.69124698142, 5628.0713682242385, 5636.267285252911, 5637.267155269809, 5641.68970706611, 5646.322441307038, 4978.875528111808, 5003.4246061702825, 5090.898182036357, 5121.078205922932, 5184.7148406837605, 5050.368703912011, 5131.512506311894, 5150.459201783796, 5155.664881782672, 5162.354705661793, 5089.821856235032, 5155.278261043438, 5194.402643695986, 5210.583153347723, 5215.40022897366, 5188.04021256067, 5205.479452054783, 5206.895862082737, 5213.452587478336, 5246.527812496894, 4910.116179087763, 4957.405111386656, 4999.575036121934, 5090.567301779353, 5092.7453627318855, 5392.245085688007, 5399.1091469907415, 5402.270693456372, 5403.78384864605, 5417.45825417459, 5950.821475355703, 5968.820935371946, 6000.819975400745, 6009.068594367836, 6016.4885984691355, 5849.917765213407, 5875.353711091777, 5974.133750606138, 6033.728482218266, 6047.425494578008, 5617.9438205618235, 5627.634203776742, 5629.127485239754, 5632.211490391349, 5634.605577609569, 6089.786857459968, 6106.603070800384, 6110.694465276719, 6118.021116621319, 6120.112583675431, 5733.283339582552, 5842.620229685057, 5848.473637372657, 5955.970220148872, 6002.969985150046, 6070.332263451017, 6073.4837538808815, 6076.605020673642, 6079.0, 6080.2399700037495, 5864.266966629171, 5999.460048595647, 6048.425399587032, 6051.243594550681, 6052.57631965767, 5882.176495290662, 5948.7323070461935, 5962.40375962409, 5966.820995370059, 6046.848828779316, 4638.652101092432, 4661.044485880454, 4675.064987002613, 4676.5791078802395, 4705.55297246761, 4049.595040495986, 4129.339305711072, 4147.543770185277, 4198.475190601175, 4243.787810609457, 5603.663780173202, 5666.093425051972, 5671.602987790815, 5680.460356266148, 5709.628874123168, 6158.076288556752, 6188.257409110935, 6191.938080619227, 6197.411245931629, 6198.597091189058, 5581.581381396411, 5633.971830140823, 5692.0608099663095, 5845.357010728816, 5849.795257165979, 5319.946800532022, 5342.813001545005, 5343.332083489564, 5351.464853514695, 5370.62405631602, 6309.558330916791, 6325.557210995186, 6326.177596912464, 6328.208973878265, 6363.777267795697, 5799.362070172193, 5930.436608522184, 5994.400559944145, 6140.17107690456, 6169.167162433194, 6260.718267677874, 6274.247090349188, 6278.623282603058, 6279.0, 6287.874242515216, 5993.400659933972, 6043.758249669964, 6045.576809623284, 6064.818055458343, 6068.575199736153, 5968.970155149196, 6010.098485227251, 6113.728279848454, 6124.615591652548, 6132.509399248139, 5944.405559444021, 5947.553933454921, 5954.225950626391, 5956.0, 5962.493188079062, 5667.631603945731, 5922.170896074467, 5926.585139040399, 5926.644401335933, 5932.080527518242, 5679.119736440858, 5722.284714410699, 5753.424657534213, 5758.280214973129, 5761.827145185651, 5896.439838215363, 5930.377310382521, 5950.137230101697, 5955.851103722268, 5966.940330596812, 5652.971735141298, 5830.912536312044, 5836.387179346278, 5839.211706419579, 5840.2991641003355], "unit": "ms", "name": "dojo.html", "value": 5565.811198579867}, {"lowerIsBetter": false, "replicates": [579.7970710251412, 619.845038740315, 626.0234034905546, 626.6709977261934, 628.481502760223, 31740.0, 32514.18714532138, 32558.348833023327, 32580.348393032156, 32595.18512037194, 41474.377884331756, 42339.0, 42448.0, 42501.362479562755, 42503.0, 9068.183863452288, 9171.862422063674, 9209.263258939302, 9217.124373184537, 9217.446953182796, 8145.226203510657, 8368.707095251668, 9048.185663290302, 9157.45055296681, 10946.726331841728, 10824.78350432991, 10915.617953371633, 10919.890801091968, 10945.945270273638, 10950.39772812495, 10833.783324333508, 10938.726531836688, 10949.069329107035, 10950.945245273802, 10953.671389858317, 10665.52005159762, 10750.354978701303, 10773.4613269337, 10787.67636970892, 10798.082163016154, 43790.78104609456, 44103.558964410266, 44161.11677766427, 44165.0, 44217.77891110524, 3566.0549954261883, 3695.297893400272, 3748.6813620842263, 3799.7530160539477, 4053.4527838741687, 32627.347453050807, 33037.33925321504, 33456.1635959103, 33515.49726754101, 33591.32817343664, 4178.353612888407, 4243.427137336451, 4247.575242475758, 4258.808353624095, 4318.589733975267, 302886.0, 309495.0, 310077.0, 310094.0, 310818.0, 58438.70780646155, 59395.40604593899, 59513.404865951656, 71141.64429177821, 72459.0, 69587.0, 70232.0, 70578.0, 70586.64706676434, 70640.6467967657, 18302.176402061938, 18471.630567388576, 18517.629647406975, 18519.629607407776, 18524.0, 13948.302584870919, 14117.43530258778, 14122.71754564903, 14254.786178207336, 14256.144631322153, 23498.29505114815, 23612.881935590212, 23641.0, 23745.643815342784, 23767.643485347733, 8850.557472126367, 8982.775430614287, 8987.46075235488, 9006.414583052081, 9058.592363343534, 1014.3153371474225, 1047.701405099543, 1047.7275908263907, 1079.7570546626946, 1212.7331986962854, 30371.0, 31527.21181970469, 34470.31059378798, 34779.0, 35415.29169416597, 1055.3984228989402, 1061.2995423020768, 1144.986686782187, 1162.1632424654404, 1182.408795602199, 14464.710705786036, 15520.689586208213, 15903.840961590467, 17340.566485837953, 18160.636787264182, 482495.0, 483205.0, 485202.0, 489578.0, 490021.0, 61498.0, 62049.689751550955, 62177.68911155415, 62178.0, 62232.377676223565, 1349.7705390083709, 1411.386047069528, 1448.2469116059801, 1454.6581553334959, 1480.2376775960388, 31837.0, 32435.0, 32521.674783251863, 32526.674733253312, 32647.347053059282, 51832.48167518277, 52592.737036314575, 52640.47359526356, 52755.20867187072, 52890.47109528856, 312262.0, 316565.0, 317551.0, 319983.0, 320367.0, 35348.0, 35757.0, 35834.8208258957, 35927.82036089907, 36128.8193559041, 46353.768231158625, 47098.822529437035, 47211.76394118008, 47329.29006064981, 47874.0, 39176.41235381414, 39573.80213098916, 39674.40488392619, 45257.32114018358, 45537.544624554655, 41978.0, 42142.57857421509, 42227.155456890076, 42833.357499636906, 43049.78475107605, 41044.0, 41696.583034170486, 41913.0, 41980.0, 42015.78992105142, 35741.82129089338, 35756.51652851335, 35899.641003590674, 36773.0, 36989.26021479609, 28258.49396478157, 28482.85758571194, 28553.0, 28578.0, 28714.0], "unit": "ms", "name": "ext.html", "value": 20885.05826060647}, {"lowerIsBetter": false, "replicates": [387.9747816391935, 388.6424489469688, 389.7427697719504, 390.2818813383374, 391.64751723448893, 15002.399904003827, 16748.246328915197, 16880.83119168808, 17858.107094645282, 17903.462896113106, 16921.407750728726, 17238.569035774082, 17253.568660783487, 18395.264189432408, 18657.720134197963, 5015.799368025284, 5116.2069879168785, 5119.462456442076, 5131.871703207413, 5139.15203991341, 3950.3679411294197, 4072.7149099563044, 4075.32757095079, 5065.721385323805, 5427.375851777037, 5591.776328946858, 5645.520130788888, 5657.23627310314, 5662.631928924628, 5671.8014869479575, 5149.2791009258735, 5323.627346085776, 5324.015057214408, 5324.1747529133045, 5330.466953304658, 5102.464241254652, 5110.10573149701, 5122.948770512322, 5296.417394086648, 5347.358317001946, 13100.60698179056, 13347.46610135603, 13354.532591359353, 18359.908200459045, 21110.15559377623, 2047.5597746484577, 2198.1097655449566, 2320.9651855222014, 2426.1387207541247, 2475.405902583367, 9931.106200441922, 10021.321961620506, 10140.89859101407, 10159.4920253987, 13225.074244802867, 1778.9021603811784, 1782.189103957696, 1822.7630408046869, 1831.258340372138, 1844.1240410804894, 60437.0, 64354.678226608565, 64417.67791161014, 64504.35495645077, 64548.3545164547, 11086.16853735973, 11096.61161859331, 11127.833082503768, 11135.610253641084, 11165.553377864957, 14667.853321466862, 14770.113793172444, 14783.70432591342, 14784.186869722269, 14810.703785924221, 8666.610002549774, 8676.78308042304, 8686.218240358397, 8735.126487351341, 8769.123087691307, 8652.826943461096, 8689.261412779859, 8699.956500217459, 8781.0, 8794.824103518022, 4418.9337159942625, 4455.064436468378, 4479.529649386767, 4481.305397663302, 4550.226461501553, 1767.6111255523765, 1818.1727314072066, 1861.3950466098302, 1900.268396667293, 1930.4980705016796, 932.1098351074708, 936.442816524167, 956.8899576548786, 994.835852084398, 1042.1714736784274, 16522.75215871763, 16927.830721692873, 17195.570110747332, 17294.13529323554, 17678.11609419524, 934.8504239321812, 944.4994153098869, 980.4362491567377, 986.7286496213455, 1001.1640280365831, 9900.108990190918, 9983.151432128205, 10003.499825008721, 10014.298999069995, 10047.196224302039, 472093.0, 651153.0, 655317.0, 658906.0, 687537.0, 67390.32609673939, 68259.0, 68444.31555684478, 70246.64876675584, 70254.2974570258, 1043.3009883378154, 1053.1153830782027, 1056.4506456642503, 1062.1184416933927, 1070.3631339353071, 14529.34617942174, 14708.705825883208, 14764.63088422798, 14811.851881481478, 15099.169545675093, 34397.48403773995, 34546.48180277348, 34605.48091778676, 34761.65238347584, 35071.12322191965, 44645.0, 46241.0, 46893.0, 47293.29060064033, 48709.756451217516, 9480.431174129575, 9690.95154524223, 9860.852087218555, 9890.0, 9891.35706179096, 11128.777424451304, 11177.105831533296, 11237.3257604544, 14237.786433203302, 17166.656666866842, 13843.584692459242, 14367.497137600134, 14447.49433769813, 14767.0, 15104.0, 14410.783838242645, 14451.566453006426, 14562.0, 14610.561683149523, 15566.766498502306, 15113.017653852465, 15411.066739305672, 15514.456994005155, 15534.0, 15864.841351586798, 9837.803243934937, 9954.701358959242, 10056.19550435978, 10095.343802652804, 10131.493425328557, 10726.678199654023, 14504.202268875313, 14797.112173269637, 14953.327100280296, 15014.849851501345], "unit": "ms", "name": "jquery.html", "value": 8972.152172698783}, {"lowerIsBetter": false, "replicates": [1567.7961864957554, 1757.771489706338, 1822.1344861190937, 1823.0337920901927, 1825.083662154738, 7026.437884969203, 7796.415268854837, 8154.91845081549, 8156.469829461081, 8156.510609363442, 7962.5620590867475, 8059.476134051282, 8112.756617301476, 8117.1476994915565, 8143.267105960463, 2350.6944097267333, 2379.214859096494, 2390.497995420964, 2394.31761947844, 2396.18529699902, 2339.5203983183405, 2370.431096536836, 2391.0316321889577, 2392.7726865947793, 2392.9880350598223, 2333.7199536055614, 2361.3860396296914, 2364.0, 2371.620540713486, 2400.927972160838, 2283.2008796920945, 2300.2064287820676, 2318.675385446039, 2321.5124823787023, 2341.672165896776, 2290.576243394977, 2305.988470057656, 2312.861228326289, 2314.36355002374, 2315.340128063494, 8035.6785728570785, 8129.4715843469985, 8210.671573137068, 8218.383621228371, 8224.218699223564, 4759.571638552512, 4814.6870453420415, 4830.855074347775, 4833.130036593412, 4836.733979631119, 3076.4769989101906, 3104.5498402731705, 3119.5788568543185, 3144.371125774854, 3151.77937544372, 965.5317171171976, 965.8647789309503, 1001.244060734141, 1017.323479885875, 1025.943573103479, 11137.498812553455, 11166.776664466746, 11167.888321116767, 11192.216544841864, 11194.216404851664, 4311.590398912098, 4320.65434765218, 4366.039471316305, 4387.254166791653, 4387.714798538149, 9520.047995200424, 9627.326087173831, 9633.036696330311, 9634.421934683942, 9641.42151470914, 4916.655834091579, 4927.655064145547, 4928.852134435972, 4929.876753081202, 4930.087933732271, 3834.827432765532, 3949.6050394960275, 4357.193919124972, 4423.026934074498, 4479.574440428154, 3963.1875465530075, 3973.3443981743267, 3978.5822488638278, 4011.117554138085, 4023.275810354106, 1227.8158276258632, 1242.3912282981273, 1244.5519612939336, 1257.2016769351528, 1278.9168704034207, 1418.4042702065158, 1419.198153043527, 1423.7081398313321, 1437.5543581489771, 1452.5206681794982, 7786.571738554434, 7886.72396466121, 7895.289423951757, 7904.288614024766, 7916.604169791487, 477.59404506169477, 489.56673344090734, 495.2942057567951, 496.42662724553196, 497.1300224606938, 6225.501959843202, 6274.6235225885675, 6301.14934483848, 6336.081268216083, 6355.3644635536075, 9557.044295570387, 9660.468674222986, 9716.61133554664, 9884.25868059884, 9890.901090989142, 9505.762355941019, 9621.951890240503, 9750.26872984529, 9764.121229089416, 9776.657816976372, 7589.0, 7750.689972401041, 7755.689772409153, 7757.495762775401, 7757.92242077572, 7481.17707052213, 7551.018367612175, 7559.357454616199, 7567.621618919142, 7691.038620172479, 8660.09069047741, 8726.389152759246, 8815.382923195315, 8851.557422128997, 8964.193222609736, 8850.955745221232, 8991.055939126298, 9059.864102038606, 9060.818783624423, 9257.907420925705, 6010.759569617167, 6451.451626611791, 7055.611941343275, 7364.447666424932, 7397.149327827154, 7942.722004729807, 7945.721899733481, 7948.0859701132795, 7987.480813747088, 7987.600619969095, 7331.303526165006, 7498.250174982458, 7513.173550909286, 7575.734849280248, 7600.885986710093, 7327.74352897646, 7398.445116616168, 7419.962900185465, 7492.550446973199, 7514.361279291322, 7284.053073100463, 7290.6719197635175, 7322.267773222635, 7326.0, 7345.77962661121, 6771.593704377753, 6789.083473730983, 6820.522563420513, 6830.59016459014, 6868.519203655696, 6910.930890691029, 6948.0, 6958.965205173941, 6960.37356637905, 6966.616836074065], "unit": "ms", "name": "mootools.html", "value": 4606.930016449621}, {"lowerIsBetter": false, "replicates": [351.37630705497736, 353.6693191865606, 355.8985689078613, 357.1375129063312, 360.9765365251257, 6163.568550201488, 6187.38126187381, 6190.597611155283, 6203.596766210193, 6210.09953556734, 5839.211706419632, 5888.057910734273, 6011.5491338149595, 6089.604175728575, 6097.695115244231, 4688.437387513503, 4705.717656940586, 4710.293455981596, 4718.292256161569, 4719.669623126382, 4691.695039822418, 4702.224133018048, 4714.292856071583, 4723.031778485402, 4732.692374995631, 4662.883427914296, 4673.252279635259, 4674.626029917598, 4678.228092364773, 4686.08621318843, 4545.659075569329, 4646.651501137429, 4694.812207511695, 4699.976500117512, 4700.130475861976, 4092.3043082676018, 4092.3656833190903, 4101.384792281152, 4103.117829666606, 4110.588941105895, 5821.155932389822, 5826.126081087828, 5835.562332825013, 5843.590948633598, 5845.7369418376275, 5349.251104845324, 5453.263809385722, 5455.1271796512765, 5462.726863656841, 5480.616356855022, 6483.124778154983, 6489.383508566679, 6491.318411566765, 6497.967510162466, 6502.0, 4528.728276303399, 4531.252343363341, 4532.0709254602725, 4541.43232095988, 4546.4544254689645, 6720.6639668016405, 6767.796966091074, 6769.0, 6772.796816095525, 6795.626240556768, 4566.292224705176, 4672.6962747421985, 4684.461286951976, 4686.742229177428, 4711.952880471152, 4738.336632871401, 4740.928886066712, 4746.359241502353, 4748.3114948332295, 4749.121412538691, 5462.0987537056735, 5467.972660136674, 5468.644538105011, 5471.534919531805, 5475.178723191473, 6430.0, 6579.440747536417, 6587.604743715392, 6589.967050164719, 6590.4512439576665, 1656.0643236571298, 1668.357682292302, 1670.6408122253774, 1673.5899704572457, 1676.3546034776455, 1033.9379637221791, 1091.3779145886767, 1114.8104822180248, 1142.8799776219353, 1145.6963904565298, 6221.782237621752, 6282.340354262828, 6285.465735412541, 6298.622082675054, 6309.337519560472, 6654.966725166343, 6656.467482601381, 6657.434118099919, 6658.334166583303, 6658.7336506539195, 6274.905876411857, 6283.654399008098, 6289.7484100636375, 6320.5891617044745, 6359.236891573041, 3673.0, 3709.350863598859, 3710.0539362462937, 3710.6103859094947, 3711.3690672585726, 6940.271271516519, 6951.7914462566205, 6954.9304506955295, 6962.060121883481, 6964.199117101497, 6780.457563394916, 6901.447884169254, 6992.230854605989, 7021.157461104598, 7024.332688394697, 6988.965055174794, 7053.858922821515, 7057.752978645723, 7058.329458701415, 7063.434925205971, 6852.451803855779, 6855.520113592001, 6859.245482996766, 6862.176538815374, 6862.416694581017, 6586.967065164644, 6765.797026089225, 6775.356341147583, 6777.2883847195335, 6783.93216067833, 7033.683484243324, 7034.331738484836, 7038.3665470107935, 7046.365827075588, 7048.471364647569, 4619.884502887455, 4654.674172808006, 4656.5110663379855, 4658.277966915133, 4659.860204193879, 6703.0, 6851.383375496229, 6851.725930962905, 6854.554453960676, 6863.931360686529, 6638.369354911276, 6648.667566621552, 6649.235337936006, 6658.500612454182, 6662.666866656745, 6588.7364505421165, 6617.470602351897, 6622.139121914121, 6630.9336906630315, 6640.601563906181, 6260.937390626218, 6402.231732192166, 6450.225972883284, 6533.640649764308, 6539.280679125388, 6476.125723027331, 6557.508186886098, 6658.201015877932, 6681.198256209286, 6693.531452798257, 6510.576812507172, 6513.2835388108215, 6517.608943463408, 6521.152250207443, 6525.1517302750335], "unit": "ms", "name": "prototype.html", "value": 4950.334459758979}, {"lowerIsBetter": false, "replicates": [811.6144831205178, 830.5473516933271, 894.9373543851929, 898.2185498616205, 938.948357840319, 13491.123077, 13736.107153035045, 13742.381592828335, 13742.656433589165, 13756.58730238092, 13439.26084065376, 13580.524681636145, 13591.0, 13596.660083497918, 13615.591532254024, 736.9373603243731, 743.9442041846856, 893.8033632600834, 901.8917729872425, 905.302916754099, 870.5212133326677, 878.4860856399014, 881.2377293640986, 882.2985726347537, 887.9067697891725, 783.6982761636754, 944.4380593546863, 950.1686024728363, 950.3917492804611, 959.2469911119772, 781.9257170568787, 919.1406035356963, 919.6827094652334, 921.2675922641482, 922.2667978956746, 681.0397339750976, 681.3016657925616, 681.4139839737821, 685.8353995041155, 691.1464341538197, 13457.058005939589, 13615.659608509768, 13650.044496885223, 13762.0, 13778.931105344509, 11989.280643161439, 12875.420606072656, 13057.804132938014, 13105.475780968749, 13149.21104733719, 10424.68725938223, 10734.248602597821, 10788.190885683527, 10791.136709063334, 10814.675559733221, 3586.1751797086554, 3632.8183590820354, 3649.051246675857, 3652.8904132876055, 3733.197362567061, 45067.54932450699, 45222.09555808865, 45305.0, 45528.77235613834, 45589.54410455886, 10172.237082218864, 10199.28604997658, 10314.226433017553, 10404.843927341097, 10489.842652360221, 18645.254189832256, 18786.624267514573, 18790.906045469685, 18960.24159033623, 18989.33537326187, 7980.3615710743, 8013.358931285483, 8098.838023239502, 8108.5540295283145, 8123.18768123183, 4452.933206001848, 4458.353538736865, 4516.36770852081, 4652.534746525321, 4662.32396302541, 3118.875244990175, 3147.0244224290545, 3147.8268695221523, 3151.889683861054, 3153.731932785693, 554.7254109215884, 575.1746244139589, 581.8545363659085, 587.4683411512588, 592.1443514122052, 561.6854561445604, 680.0547239337325, 686.2931180883697, 713.1620346093423, 721.8195451137216, 8388.664453421918, 8532.233212386327, 8584.699535516227, 8605.827883442296, 8789.208971192624, 660.4419265720459, 668.6990854115668, 693.1855070292386, 693.928623060165, 713.9643017849087, 3838.692904567628, 3969.3649016157856, 4325.178216138924, 4404.185225733249, 4435.844745433958, 54441.45558544364, 55301.0, 55642.443575563724, 55805.16292255619, 55961.16058259129, 48674.756626216644, 53675.46324536783, 53793.1931021035, 54521.1821822673, 54616.45383546193, 45805.77097114493, 45853.31220031702, 46057.30914036292, 47314.763426182646, 47644.76177619159, 9157.679481218125, 9267.44395336255, 9321.673741419017, 9341.159295663423, 9535.618575256913, 34665.306693865474, 35405.0, 35772.4634130483, 35962.64037359593, 36124.4581331275, 44036.0, 44095.0, 44105.0, 44325.77837110794, 45006.77496612496, 11243.425920370992, 11626.186166968231, 11708.88291117078, 11780.175387722777, 12183.75632487363, 12074.27554346742, 12439.378031098227, 12907.935460322638, 13127.146735462164, 13529.255890926093, 10446.843297350393, 10651.57393704243, 11666.125040621817, 11921.761564768483, 12411.875881241072, 11654.067674585844, 12028.518859245532, 12081.939590301992, 12086.0, 12287.631371058882, 9119.971281792672, 10079.193664506674, 11193.832092518782, 11442.084633229488, 11792.174547781917, 10163.441010744184, 10175.79648407013, 10291.073254683042, 10332.276740628084, 10539.894601053891, 9774.169195618451, 10141.84787228207, 10217.59129634836, 10244.590216391261, 10248.692539223835], "unit": "ms", "name": "yui.html", "value": 6523.758799527995}], "name": "dromaeo_css", "value": 7330.6226071072815}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 103.0, 111.0, 108.0, 109.0, 97.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.5}, {"lowerIsBetter": true, "replicates": [156.0, 122.0, 149.0, 167.0, 125.0, 143.0, 154.0, 138.0, 156.0, 128.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.8}, {"lowerIsBetter": true, "replicates": [161.0, 163.0, 162.0, 161.0, 162.0, 161.0, 162.0, 167.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 162.0}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 71.0, 145.0, 71.0, 72.0, 72.0, 71.0, 153.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 87.0}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 136.0, 92.0, 92.0, 92.0, 143.0, 91.0, 92.0, 138.0], "unit": "ms", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [147.0, 145.0, 147.0, 144.0, 147.0, 146.0, 146.0, 144.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 101.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 57.0, 58.0, 56.0, 56.0, 58.0, 56.0, 55.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [65.0, 63.0, 64.0, 64.0, 65.0, 64.0, 63.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.0}, {"lowerIsBetter": true, "replicates": [81.0, 90.0, 81.0, 80.0, 81.0, 81.0, 81.0, 83.0, 82.0, 82.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 82.2}, {"lowerIsBetter": true, "replicates": [121.0, 119.0, 117.0, 111.0, 119.0, 120.0, 117.0, 119.0, 120.0, 121.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.4}, {"lowerIsBetter": true, "replicates": [147.0, 141.0, 146.0, 147.0, 152.0, 137.0, 157.0, 157.0, 141.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.8}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 64.0, 64.0, 65.0, 65.0, 64.0, 65.0, 68.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.6}], "name": "kraken", "value": 1489.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.54879487611663], "unit": "score", "name": "Richards", "value": 209.44676600000008}, {"lowerIsBetter": false, "replicates": [167.7289500167729], "unit": "score", "name": "DeltaBlue", "value": 394.19551600000005}, {"lowerIsBetter": false, "replicates": [278.70680044593087], "unit": "score", "name": "Encrypt", "value": 955.0574279999998}, {"lowerIsBetter": false, "replicates": [5128.205128205128], "unit": "score", "name": "Decrypt", "value": 51.90529499999997}, {"lowerIsBetter": false, "replicates": [940.7337723424271], "unit": "score", "name": "RayTrace", "value": 786.6083070000002}, {"lowerIsBetter": false, "replicates": [542.8881650380022], "unit": "score", "name": "Earley", "value": 1227.624846}, {"lowerIsBetter": false, "replicates": [10547.368421052632], "unit": "score", "name": "Boyer", "value": 63.187609780439146}, {"lowerIsBetter": false, "replicates": [26256.410256410258], "unit": "score", "name": "RegExp", "value": 34.69571777343749}, {"lowerIsBetter": false, "replicates": [530.2226935312831], "unit": "score", "name": "Splay", "value": 153.69202600000006}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23067.583823037552}]} 01:36:42 INFO - RETURN: dromaeo_css: 7330.62 01:36:42 INFO - RETURN: kraken: 1489.40 01:36:42 INFO - RETURN: v8_7: 23067.58 01:36:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23067.58"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1489.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7330.62"}}} 01:36:42 INFO - Return code: 0 01:36:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 01:36:42 INFO - # TBPL SUCCESS # 01:36:42 INFO - Running post-action listener: _resource_record_post_action 01:36:42 INFO - Running post-run listener: _resource_record_post_run 01:36:43 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 8192; Write bytes: 503824384; Read time: 124; Write time: 490384 01:36:43 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 239456256; Read time: 112; Write time: 279560 01:36:43 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 4096; Write bytes: 264368128; Read time: 12; Write time: 210824 01:36:43 INFO - Running post-run listener: _upload_blobber_files 01:36:43 INFO - Blob upload gear active. 01:36:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 01:36:43 INFO - Copying logs to upload dir... 01:36:43 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.691158 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-01-26 01:36:42.892397) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:36:42.895671) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.008714 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:36:42.944907) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:36:42.945303) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453799747.620461-563395581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004659 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:36:42.995674) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 01:36:42.996046) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 01:36:42.996392) ========= ========= Total master_lag: 0.26 =========