builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1455116839.57 results: success (0) buildid: 20160210043321 builduid: 52de328865b24d1d80be254ee048cca7 revision: 4febbf1c7a8e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.571975) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.572443) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.572740) ========= 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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004367 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.593537) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.593863) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.610731) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.611049) ========= 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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004571 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.660126) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.663479) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.663858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.664156) ========= 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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-10 07:07:20-- 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% 37.6M=0s 2016-02-10 07:07:20 (37.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.192290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.871561) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.871913) ========= 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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014302 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.921124) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:19.924543) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4febbf1c7a8e --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 4febbf1c7a8e --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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-10 07:07:20,560 Setting DEBUG logging. 2016-02-10 07:07:20,560 attempt 1/10 2016-02-10 07:07:20,560 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4febbf1c7a8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-10 07:07:20,817 unpacking tar archive at: mozilla-beta-4febbf1c7a8e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.548790 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:20.486210) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:20.486581) ========= echo 4febbf1c7a8e in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'4febbf1c7a8e'] 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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 4febbf1c7a8e program finished with exit code 0 elapsedTime=0.004680 script_repo_revision: '4febbf1c7a8e' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:20.537157) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:20.537480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:20.547553) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-02-10 07:07:20.547892) ========= /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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:07:21 INFO - MultiFileLogger online at 20160210 07:07:21 in /builds/slave/test-pgo 07:07:21 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 07:07:21 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 07:07:21 INFO - {'append_to_log': False, 07:07:21 INFO - 'base_work_dir': '/builds/slave/test-pgo', 07:07:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:07:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 07:07:21 INFO - 'branch': 'Mozilla-Beta', 07:07:21 INFO - 'buildbot_json_path': 'buildprops.json', 07:07:21 INFO - 'config_files': ('talos/linux_config.py',), 07:07:21 INFO - 'default_actions': ('clobber', 07:07:21 INFO - 'read-buildbot-config', 07:07:21 INFO - 'download-and-extract', 07:07:21 INFO - 'populate-webroot', 07:07:21 INFO - 'create-virtualenv', 07:07:21 INFO - 'install', 07:07:21 INFO - 'run-tests'), 07:07:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:07:21 INFO - 'download_minidump_stackwalk': True, 07:07:21 INFO - 'download_symbols': 'ondemand', 07:07:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:07:21 INFO - 'tooltool.py': '/tools/tooltool.py', 07:07:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:07:21 INFO - '/tools/misc-python/virtualenv.py')}, 07:07:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:07:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:07:21 INFO - 'installer_path': 'installer.exe', 07:07:21 INFO - 'log_level': 'info', 07:07:21 INFO - 'log_name': 'talos', 07:07:21 INFO - 'log_to_console': True, 07:07:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:07:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:07:21 INFO - 'opt_config_files': (), 07:07:21 INFO - 'pip_index': False, 07:07:21 INFO - 'sps_profile': False, 07:07:21 INFO - 'sps_profile_interval': 0, 07:07:21 INFO - 'suite': 'dromaeojs', 07:07:21 INFO - 'system_bits': '32', 07:07:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:07:21 INFO - 'title': 'talos-linux64-ix-069', 07:07:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:07:21 INFO - 'use_talos_json': True, 07:07:21 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 07:07:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:07:21 INFO - 'work_dir': 'build'} 07:07:21 INFO - ##### 07:07:21 INFO - ##### Running clobber step. 07:07:21 INFO - ##### 07:07:21 INFO - Running pre-action listener: _resource_record_pre_action 07:07:21 INFO - Running main action method: clobber 07:07:21 INFO - rmtree: /builds/slave/test-pgo/build 07:07:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 07:07:21 INFO - Running post-action listener: _resource_record_post_action 07:07:21 INFO - ##### 07:07:21 INFO - ##### Running read-buildbot-config step. 07:07:21 INFO - ##### 07:07:21 INFO - Running pre-action listener: _resource_record_pre_action 07:07:21 INFO - Running main action method: read_buildbot_config 07:07:21 INFO - Using buildbot properties: 07:07:21 INFO - { 07:07:21 INFO - "properties": { 07:07:21 INFO - "buildnumber": 115, 07:07:21 INFO - "product": "firefox", 07:07:21 INFO - "script_repo_revision": "production", 07:07:21 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 07:07:21 INFO - "repository": "", 07:07:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 07:07:21 INFO - "buildid": "20160210043321", 07:07:21 INFO - "slavename": "talos-linux64-ix-069", 07:07:21 INFO - "pgo_build": "True", 07:07:21 INFO - "basedir": "/builds/slave/test-pgo", 07:07:21 INFO - "project": "", 07:07:21 INFO - "platform": "ubuntu64_hw", 07:07:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:07:21 INFO - "slavebuilddir": "test", 07:07:21 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 07:07:21 INFO - "branch": "mozilla-beta", 07:07:21 INFO - "repo_path": "releases/mozilla-beta", 07:07:21 INFO - "stage_platform": "linux64-pgo", 07:07:21 INFO - "builduid": "52de328865b24d1d80be254ee048cca7", 07:07:21 INFO - "revision": "4febbf1c7a8e" 07:07:21 INFO - }, 07:07:21 INFO - "sourcestamp": { 07:07:21 INFO - "repository": "", 07:07:21 INFO - "hasPatch": false, 07:07:21 INFO - "project": "", 07:07:21 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 07:07:21 INFO - "changes": [ 07:07:21 INFO - { 07:07:21 INFO - "category": null, 07:07:21 INFO - "files": [ 07:07:21 INFO - { 07:07:21 INFO - "url": null, 07:07:21 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.tar.bz2" 07:07:21 INFO - } 07:07:21 INFO - ], 07:07:21 INFO - "repository": "", 07:07:21 INFO - "rev": "4febbf1c7a8e", 07:07:21 INFO - "who": "sendchange", 07:07:21 INFO - "when": 1455116776, 07:07:21 INFO - "number": 7588138, 07:07:21 INFO - "comments": "Bug 1240848: Adds additional instructions to x64 detour patcher and prevents register clobbering in jmp from trampoline_ r=ehsan, a=sylvestre", 07:07:21 INFO - "project": "", 07:07:21 INFO - "at": "Wed 10 Feb 2016 07:06:16", 07:07:21 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 07:07:21 INFO - "revlink": "", 07:07:21 INFO - "properties": [ 07:07:21 INFO - [ 07:07:21 INFO - "buildid", 07:07:21 INFO - "20160210043321", 07:07:21 INFO - "Change" 07:07:21 INFO - ], 07:07:21 INFO - [ 07:07:21 INFO - "builduid", 07:07:21 INFO - "52de328865b24d1d80be254ee048cca7", 07:07:21 INFO - "Change" 07:07:21 INFO - ], 07:07:21 INFO - [ 07:07:21 INFO - "pgo_build", 07:07:21 INFO - "True", 07:07:21 INFO - "Change" 07:07:21 INFO - ] 07:07:21 INFO - ], 07:07:21 INFO - "revision": "4febbf1c7a8e" 07:07:21 INFO - } 07:07:21 INFO - ], 07:07:21 INFO - "revision": "4febbf1c7a8e" 07:07:21 INFO - } 07:07:21 INFO - } 07:07:21 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.tar.bz2. 07:07:21 INFO - Running post-action listener: _resource_record_post_action 07:07:21 INFO - ##### 07:07:21 INFO - ##### Running download-and-extract step. 07:07:21 INFO - ##### 07:07:21 INFO - Running pre-action listener: _resource_record_pre_action 07:07:21 INFO - Running main action method: download_and_extract 07:07:21 INFO - mkdir: /builds/slave/test-pgo/build/tests 07:07:21 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')]} 07:07:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/test_packages.json 07:07:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 07:07:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 07:07:21 INFO - Downloaded 1270 bytes. 07:07:21 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 07:07:21 INFO - Using the following test package requirements: 07:07:21 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 07:07:21 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:07:21 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 07:07:21 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:07:21 INFO - u'jsshell-linux-x86_64.zip'], 07:07:21 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:07:21 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 07:07:21 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 07:07:21 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:07:21 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 07:07:21 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:07:21 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 07:07:21 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:07:21 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 07:07:21 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 07:07:21 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 07:07:21 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 07:07:21 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:07:21 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')]} 07:07:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:07:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:22 INFO - Downloaded 21581539 bytes. 07:07:22 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 07:07:22 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:07:22 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 07:07:23 INFO - Return code: 0 07:07:23 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 07:07:23 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')]} 07:07:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:07:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:23 INFO - Downloaded 21581539 bytes. 07:07:23 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 07:07:23 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 07:07:23 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 07:07:25 INFO - Return code: 0 07:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 07:07:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:26 INFO - Downloaded 11132038 bytes. 07:07:26 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 07:07:26 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 07:07:26 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 07:07:26 INFO - Return code: 0 07:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.tar.bz2 07:07:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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 07:07:37 INFO - Downloaded 57941197 bytes. 07:07:37 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.tar.bz2 07:07:37 INFO - mkdir: /builds/slave/test-pgo/properties 07:07:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 07:07:37 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 07:07:37 INFO - Contents: 07:07:37 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.tar.bz2 07:07:37 INFO - Running post-action listener: _resource_record_post_action 07:07:37 INFO - Running post-action listener: set_extra_try_arguments 07:07:37 INFO - ##### 07:07:37 INFO - ##### Running populate-webroot step. 07:07:37 INFO - ##### 07:07:37 INFO - Running pre-action listener: _resource_record_pre_action 07:07:37 INFO - Running main action method: populate_webroot 07:07:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:07:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:07:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:07:37 INFO - '--tppagecycles', 07:07:37 INFO - '7'], 07:07:37 INFO - 'tests': ['tsvgm']}}, 07:07:37 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:07:37 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:07:37 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:07:37 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:07:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'tests': ['damp', 'tps']}, 07:07:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['damp', 'tps']}, 07:07:37 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:07:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:07:37 INFO - 'other': {'tests': ['a11yr', 07:07:37 INFO - 'ts_paint', 07:07:37 INFO - 'tpaint', 07:07:37 INFO - 'sessionrestore', 07:07:37 INFO - 'sessionrestore_no_auto_restore']}, 07:07:37 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['a11yr', 07:07:37 INFO - 'ts_paint', 07:07:37 INFO - 'tpaint', 07:07:37 INFO - 'sessionrestore', 07:07:37 INFO - 'sessionrestore_no_auto_restore']}, 07:07:37 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['a11yr', 07:07:37 INFO - 'ts_paint', 07:07:37 INFO - 'tpaint', 07:07:37 INFO - 'sessionrestore', 07:07:37 INFO - 'sessionrestore_no_auto_restore']}, 07:07:37 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['a11yr', 07:07:37 INFO - 'ts_paint', 07:07:37 INFO - 'tpaint', 07:07:37 INFO - 'sessionrestore', 07:07:37 INFO - 'sessionrestore_no_auto_restore']}, 07:07:37 INFO - 'other_l64': {'tests': ['a11yr', 07:07:37 INFO - 'ts_paint', 07:07:37 INFO - 'tpaint', 07:07:37 INFO - 'sessionrestore', 07:07:37 INFO - 'sessionrestore_no_auto_restore']}, 07:07:37 INFO - 'other_nol64': {'tests': ['a11yr', 07:07:37 INFO - 'ts_paint', 07:07:37 INFO - 'tpaint', 07:07:37 INFO - 'sessionrestore', 07:07:37 INFO - 'sessionrestore_no_auto_restore']}, 07:07:37 INFO - 'svgr': {'tests': ['tsvgx', 07:07:37 INFO - 'tsvgr_opacity', 07:07:37 INFO - 'tart', 07:07:37 INFO - 'tscrollx', 07:07:37 INFO - 'cart']}, 07:07:37 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['tsvgx', 07:07:37 INFO - 'tsvgr_opacity', 07:07:37 INFO - 'tart', 07:07:37 INFO - 'tscrollx', 07:07:37 INFO - 'cart']}, 07:07:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'tests': ['tp5o']}, 07:07:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'talos_options': ['--e10s'], 07:07:37 INFO - 'tests': ['tp5o']}, 07:07:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'talos_options': ['--xperf_path', 07:07:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:37 INFO - 'tests': ['tp5n']}, 07:07:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:37 INFO - 'talos_options': ['--e10s', 07:07:37 INFO - '--xperf_path', 07:07:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:37 INFO - 'tests': ['tp5n']}}, 07:07:37 INFO - 'talos.zip': {'path': '', 07:07:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:07:37 INFO - Running post-action listener: _resource_record_post_action 07:07:37 INFO - ##### 07:07:37 INFO - ##### Running create-virtualenv step. 07:07:37 INFO - ##### 07:07:37 INFO - Running pre-action listener: _resource_record_pre_action 07:07:37 INFO - Running main action method: create_virtualenv 07:07:37 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 07:07:37 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 07:07:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 07:07:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:07:37 INFO - Using real prefix '/usr' 07:07:37 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 07:07:37 INFO - Installing distribute.............................................................................................................................................................................................done. 07:07:39 INFO - Installing pip.................done. 07:07:39 INFO - Return code: 0 07:07:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 07:07:39 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')]} 07:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:39 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')]} 07:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:39 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:39 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 07:07:39 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 07:07:39 INFO - Using env: {'DISPLAY': ':0', 07:07:39 INFO - 'HOME': '/home/cltbld', 07:07:39 INFO - 'LANG': 'en_US.UTF-8', 07:07:39 INFO - 'LANGUAGE': 'en_US:en', 07:07:39 INFO - 'LOGNAME': 'cltbld', 07:07:39 INFO - 'MAIL': '/var/mail/cltbld', 07:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:39 INFO - 'MOZ_NO_REMOTE': '1', 07:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:39 INFO - 'NO_EM_RESTART': '1', 07:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:39 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:39 INFO - 'SHELL': '/bin/bash', 07:07:39 INFO - 'SHLVL': '1', 07:07:39 INFO - 'TERM': 'linux', 07:07:39 INFO - 'TMOUT': '86400', 07:07:39 INFO - 'USER': 'cltbld', 07:07:39 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:39 INFO - '_': '/tools/buildbot/bin/python'} 07:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:40 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 07:07:40 INFO - Cleaning up... 07:07:40 INFO - Return code: 0 07:07:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 07:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:40 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:40 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 07:07:40 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 07:07:40 INFO - Using env: {'DISPLAY': ':0', 07:07:40 INFO - 'HOME': '/home/cltbld', 07:07:40 INFO - 'LANG': 'en_US.UTF-8', 07:07:40 INFO - 'LANGUAGE': 'en_US:en', 07:07:40 INFO - 'LOGNAME': 'cltbld', 07:07:40 INFO - 'MAIL': '/var/mail/cltbld', 07:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:40 INFO - 'MOZ_NO_REMOTE': '1', 07:07:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:40 INFO - 'NO_EM_RESTART': '1', 07:07:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:40 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:40 INFO - 'SHELL': '/bin/bash', 07:07:40 INFO - 'SHLVL': '1', 07:07:40 INFO - 'TERM': 'linux', 07:07:40 INFO - 'TMOUT': '86400', 07:07:40 INFO - 'USER': 'cltbld', 07:07:40 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:40 INFO - '_': '/tools/buildbot/bin/python'} 07:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:40 INFO - Downloading/unpacking psutil>=0.7.1 07:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 07:07:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:42 INFO - Installing collected packages: psutil 07:07:42 INFO - Running setup.py install for psutil 07:07:42 INFO - building 'psutil._psutil_linux' extension 07:07:42 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 07:07:42 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 07:07:42 INFO - building 'psutil._psutil_posix' extension 07:07:42 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 07:07:42 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 07:07:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:42 INFO - Successfully installed psutil 07:07:42 INFO - Cleaning up... 07:07:42 INFO - Return code: 0 07:07:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 07:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:42 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:42 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 07:07:42 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 07:07:42 INFO - Using env: {'DISPLAY': ':0', 07:07:42 INFO - 'HOME': '/home/cltbld', 07:07:42 INFO - 'LANG': 'en_US.UTF-8', 07:07:42 INFO - 'LANGUAGE': 'en_US:en', 07:07:42 INFO - 'LOGNAME': 'cltbld', 07:07:42 INFO - 'MAIL': '/var/mail/cltbld', 07:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:42 INFO - 'MOZ_NO_REMOTE': '1', 07:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:42 INFO - 'NO_EM_RESTART': '1', 07:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:42 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:42 INFO - 'SHELL': '/bin/bash', 07:07:42 INFO - 'SHLVL': '1', 07:07:42 INFO - 'TERM': 'linux', 07:07:42 INFO - 'TMOUT': '86400', 07:07:42 INFO - 'USER': 'cltbld', 07:07:42 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:42 INFO - '_': '/tools/buildbot/bin/python'} 07:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:07:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:07:44 INFO - Installing collected packages: mozsystemmonitor 07:07:44 INFO - Running setup.py install for mozsystemmonitor 07:07:44 INFO - Successfully installed mozsystemmonitor 07:07:44 INFO - Cleaning up... 07:07:45 INFO - Return code: 0 07:07:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 07:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:45 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:45 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 07:07:45 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 07:07:45 INFO - Using env: {'DISPLAY': ':0', 07:07:45 INFO - 'HOME': '/home/cltbld', 07:07:45 INFO - 'LANG': 'en_US.UTF-8', 07:07:45 INFO - 'LANGUAGE': 'en_US:en', 07:07:45 INFO - 'LOGNAME': 'cltbld', 07:07:45 INFO - 'MAIL': '/var/mail/cltbld', 07:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:45 INFO - 'MOZ_NO_REMOTE': '1', 07:07:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:45 INFO - 'NO_EM_RESTART': '1', 07:07:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:45 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:45 INFO - 'SHELL': '/bin/bash', 07:07:45 INFO - 'SHLVL': '1', 07:07:45 INFO - 'TERM': 'linux', 07:07:45 INFO - 'TMOUT': '86400', 07:07:45 INFO - 'USER': 'cltbld', 07:07:45 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:45 INFO - '_': '/tools/buildbot/bin/python'} 07:07:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:45 INFO - Downloading/unpacking blobuploader==1.2.4 07:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:46 INFO - Downloading blobuploader-1.2.4.tar.gz 07:07:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:07:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 07:07:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:47 INFO - Downloading docopt-0.6.1.tar.gz 07:07:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 07:07:47 INFO - Installing collected packages: blobuploader, requests, docopt 07:07:47 INFO - Running setup.py install for blobuploader 07:07:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:07:47 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 07:07:47 INFO - Running setup.py install for requests 07:07:48 INFO - Running setup.py install for docopt 07:07:48 INFO - Successfully installed blobuploader requests docopt 07:07:48 INFO - Cleaning up... 07:07:48 INFO - Return code: 0 07:07:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:07:48 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')]} 07:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:48 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')]} 07:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:48 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:48 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 07:07:48 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 07:07:48 INFO - Using env: {'DISPLAY': ':0', 07:07:48 INFO - 'HOME': '/home/cltbld', 07:07:48 INFO - 'LANG': 'en_US.UTF-8', 07:07:48 INFO - 'LANGUAGE': 'en_US:en', 07:07:48 INFO - 'LOGNAME': 'cltbld', 07:07:48 INFO - 'MAIL': '/var/mail/cltbld', 07:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:48 INFO - 'MOZ_NO_REMOTE': '1', 07:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:48 INFO - 'NO_EM_RESTART': '1', 07:07:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:48 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:48 INFO - 'SHELL': '/bin/bash', 07:07:48 INFO - 'SHLVL': '1', 07:07:48 INFO - 'TERM': 'linux', 07:07:48 INFO - 'TMOUT': '86400', 07:07:48 INFO - 'USER': 'cltbld', 07:07:48 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:48 INFO - '_': '/tools/buildbot/bin/python'} 07:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:07:48 INFO - Running setup.py (path:/tmp/pip-yearTx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:07:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:07:48 INFO - Running setup.py (path:/tmp/pip-CdiLl2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:07:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:07:48 INFO - Running setup.py (path:/tmp/pip-X5_zTf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:07:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:07:48 INFO - Running setup.py (path:/tmp/pip-VVJHRu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:07:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:07:48 INFO - Running setup.py (path:/tmp/pip-I2fTNG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:07:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:07:48 INFO - Running setup.py (path:/tmp/pip-utaQEQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:07:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:07:48 INFO - Running setup.py (path:/tmp/pip-__3sCt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:07:49 INFO - Running setup.py (path:/tmp/pip-65hyKk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:07:49 INFO - Running setup.py (path:/tmp/pip-gv3y5Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:07:49 INFO - Running setup.py (path:/tmp/pip-ratWQu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:07:49 INFO - Running setup.py (path:/tmp/pip-TkU0K1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:07:49 INFO - Running setup.py (path:/tmp/pip-7epHou-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:07:49 INFO - Running setup.py (path:/tmp/pip-K3ikWD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:07:49 INFO - Running setup.py (path:/tmp/pip-VGQhBs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:07:49 INFO - Running setup.py (path:/tmp/pip-CvCkzP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:07:49 INFO - Running setup.py (path:/tmp/pip-qXgJQj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:07:49 INFO - Running setup.py (path:/tmp/pip-3iSCng-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:07:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:07:49 INFO - Running setup.py install for manifestparser 07:07:49 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 07:07:49 INFO - Running setup.py install for mozcrash 07:07:49 INFO - Running setup.py install for mozdebug 07:07:50 INFO - Running setup.py install for mozdevice 07:07:50 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Running setup.py install for mozfile 07:07:50 INFO - Running setup.py install for mozhttpd 07:07:50 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Running setup.py install for mozinfo 07:07:50 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Running setup.py install for mozInstall 07:07:50 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Running setup.py install for mozleak 07:07:50 INFO - Running setup.py install for mozlog 07:07:50 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Running setup.py install for moznetwork 07:07:50 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 07:07:50 INFO - Running setup.py install for mozprocess 07:07:51 INFO - Running setup.py install for mozprofile 07:07:51 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 07:07:51 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 07:07:51 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 07:07:51 INFO - Running setup.py install for mozrunner 07:07:51 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 07:07:51 INFO - Running setup.py install for mozscreenshot 07:07:51 INFO - Running setup.py install for moztest 07:07:51 INFO - Running setup.py install for mozversion 07:07:51 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 07:07:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:07:51 INFO - Cleaning up... 07:07:51 INFO - Return code: 0 07:07:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:07: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')]} 07:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07: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')]} 07:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:51 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:51 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 07:07:51 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 07:07:51 INFO - Using env: {'DISPLAY': ':0', 07:07:51 INFO - 'HOME': '/home/cltbld', 07:07:51 INFO - 'LANG': 'en_US.UTF-8', 07:07:51 INFO - 'LANGUAGE': 'en_US:en', 07:07:51 INFO - 'LOGNAME': 'cltbld', 07:07:51 INFO - 'MAIL': '/var/mail/cltbld', 07:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:51 INFO - 'MOZ_NO_REMOTE': '1', 07:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:51 INFO - 'NO_EM_RESTART': '1', 07:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:51 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:51 INFO - 'SHELL': '/bin/bash', 07:07:51 INFO - 'SHLVL': '1', 07:07:51 INFO - 'TERM': 'linux', 07:07:51 INFO - 'TMOUT': '86400', 07:07:51 INFO - 'USER': 'cltbld', 07:07:51 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:51 INFO - '_': '/tools/buildbot/bin/python'} 07:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:07:51 INFO - Running setup.py (path:/tmp/pip-sAsgzf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:07:51 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)) 07:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:07:51 INFO - Running setup.py (path:/tmp/pip-mIY5nY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:07:51 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)) 07:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:07:51 INFO - Running setup.py (path:/tmp/pip-kmwZRx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:07:52 INFO - Running setup.py (path:/tmp/pip-zpf3gp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:07:52 INFO - Running setup.py (path:/tmp/pip-q5KeUR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:07:52 INFO - Running setup.py (path:/tmp/pip-sVY0dD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:07:52 INFO - Running setup.py (path:/tmp/pip-jnncNA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:07:52 INFO - Running setup.py (path:/tmp/pip-Xm4nEm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:07:52 INFO - Running setup.py (path:/tmp/pip-2GAB3g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:07:52 INFO - Running setup.py (path:/tmp/pip-wvVNBo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:07:52 INFO - Running setup.py (path:/tmp/pip-7B0MCo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:07:52 INFO - Running setup.py (path:/tmp/pip-i_wsnr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:07:52 INFO - Running setup.py (path:/tmp/pip-1LnIZ5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:07:52 INFO - Running setup.py (path:/tmp/pip-iKaik2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:07:52 INFO - Running setup.py (path:/tmp/pip-BeY5Us-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:07:52 INFO - Running setup.py (path:/tmp/pip-qrhPyq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:07:52 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)) 07:07:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:07:52 INFO - Running setup.py (path:/tmp/pip-Jc9cQh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:07:53 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)) 07:07:53 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)) 07:07:53 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)) 07:07:53 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)) 07:07:53 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)) 07:07:53 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)) 07:07:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 07:07:53 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 07:07:53 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 07:07:53 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 07:07:53 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 07:07:53 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 07:07:53 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 07:07:54 INFO - Downloading blessings-1.6.tar.gz 07:07:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 07:07:54 INFO - Installing collected packages: blessings 07:07:54 INFO - Running setup.py install for blessings 07:07:55 INFO - Successfully installed blessings 07:07:55 INFO - Cleaning up... 07:07:55 INFO - Return code: 0 07:07:55 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 07:07:55 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:07:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:07:55 INFO - Reading from file tmpfile_stdout 07:07:55 INFO - Current package versions: 07:07:55 INFO - argparse == 1.2.1 07:07:55 INFO - blessings == 1.6 07:07:55 INFO - blobuploader == 1.2.4 07:07:55 INFO - docopt == 0.6.1 07:07:55 INFO - manifestparser == 1.1 07:07:55 INFO - mozInstall == 1.12 07:07:55 INFO - mozcrash == 0.16 07:07:55 INFO - mozdebug == 0.1 07:07:55 INFO - mozdevice == 0.47 07:07:55 INFO - mozfile == 1.2 07:07:55 INFO - mozhttpd == 0.7 07:07:55 INFO - mozinfo == 0.9 07:07:55 INFO - mozleak == 0.1 07:07:55 INFO - mozlog == 3.1 07:07:55 INFO - moznetwork == 0.27 07:07:55 INFO - mozprocess == 0.22 07:07:55 INFO - mozprofile == 0.27 07:07:55 INFO - mozrunner == 6.11 07:07:55 INFO - mozscreenshot == 0.1 07:07:55 INFO - mozsystemmonitor == 0.0 07:07:55 INFO - moztest == 0.7 07:07:55 INFO - mozversion == 1.4 07:07:55 INFO - psutil == 3.1.1 07:07:55 INFO - requests == 1.2.3 07:07:55 INFO - wsgiref == 0.1.2 07:07:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:07:55 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')]} 07:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:55 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')]} 07:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:55 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:55 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 07:07:55 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 07:07:55 INFO - Using env: {'DISPLAY': ':0', 07:07:55 INFO - 'HOME': '/home/cltbld', 07:07:55 INFO - 'LANG': 'en_US.UTF-8', 07:07:55 INFO - 'LANGUAGE': 'en_US:en', 07:07:55 INFO - 'LOGNAME': 'cltbld', 07:07:55 INFO - 'MAIL': '/var/mail/cltbld', 07:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:55 INFO - 'MOZ_NO_REMOTE': '1', 07:07:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:55 INFO - 'NO_EM_RESTART': '1', 07:07:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:55 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:55 INFO - 'SHELL': '/bin/bash', 07:07:55 INFO - 'SHLVL': '1', 07:07:55 INFO - 'TERM': 'linux', 07:07:55 INFO - 'TMOUT': '86400', 07:07:55 INFO - 'USER': 'cltbld', 07:07:55 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:55 INFO - '_': '/tools/buildbot/bin/python'} 07:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 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)) 07:07:55 INFO - Cleaning up... 07:07:55 INFO - Return code: 0 07:07:55 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 07:07:55 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')]} 07:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:55 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')]} 07:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:55 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 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07:55 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 07:07:55 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 07:07:55 INFO - Using env: {'DISPLAY': ':0', 07:07:55 INFO - 'HOME': '/home/cltbld', 07:07:55 INFO - 'LANG': 'en_US.UTF-8', 07:07:55 INFO - 'LANGUAGE': 'en_US:en', 07:07:55 INFO - 'LOGNAME': 'cltbld', 07:07:55 INFO - 'MAIL': '/var/mail/cltbld', 07:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:55 INFO - 'MOZ_NO_REMOTE': '1', 07:07:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:55 INFO - 'NO_EM_RESTART': '1', 07:07:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:55 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:55 INFO - 'SHELL': '/bin/bash', 07:07:55 INFO - 'SHLVL': '1', 07:07:55 INFO - 'TERM': 'linux', 07:07:55 INFO - 'TMOUT': '86400', 07:07:55 INFO - 'USER': 'cltbld', 07:07:55 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:55 INFO - '_': '/tools/buildbot/bin/python'} 07:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:55 INFO - Downloading/unpacking jsonschema 07:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:07:57 INFO - Downloading/unpacking functools32 (from jsonschema) 07:07: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 07:07: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 07:07: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 07:07: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 07:07: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 07:07: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 07:07:57 INFO - Downloading functools32-3.2.3-2.tar.gz 07:07:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 07:07:57 INFO - warning: no files found matching '*.txt' 07:07:57 INFO - no previously-included directories found matching 'build' 07:07:57 INFO - no previously-included directories found matching 'dist' 07:07:57 INFO - no previously-included directories found matching '.git*' 07:07:57 INFO - Installing collected packages: jsonschema, functools32 07:07:57 INFO - Running setup.py install for functools32 07:07:57 INFO - warning: no files found matching '*.txt' 07:07:57 INFO - no previously-included directories found matching 'build' 07:07:57 INFO - no previously-included directories found matching 'dist' 07:07:57 INFO - no previously-included directories found matching '.git*' 07:07:57 INFO - Successfully installed jsonschema functools32 07:07:57 INFO - Cleaning up... 07:07:57 INFO - Return code: 0 07:07:57 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 07:07: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')]} 07:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07: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')]} 07:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f35ac999f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b0a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, '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': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', '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 07:07: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', 'flake8'] in /builds/slave/test-pgo/build 07:07: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 flake8 07:07:57 INFO - Using env: {'DISPLAY': ':0', 07:07:57 INFO - 'HOME': '/home/cltbld', 07:07:57 INFO - 'LANG': 'en_US.UTF-8', 07:07:57 INFO - 'LANGUAGE': 'en_US:en', 07:07:57 INFO - 'LOGNAME': 'cltbld', 07:07:57 INFO - 'MAIL': '/var/mail/cltbld', 07:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:57 INFO - 'MOZ_NO_REMOTE': '1', 07:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:57 INFO - 'NO_EM_RESTART': '1', 07:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:07:57 INFO - 'PWD': '/builds/slave/test-pgo', 07:07:57 INFO - 'SHELL': '/bin/bash', 07:07:57 INFO - 'SHLVL': '1', 07:07:57 INFO - 'TERM': 'linux', 07:07:57 INFO - 'TMOUT': '86400', 07:07:57 INFO - 'USER': 'cltbld', 07:07:57 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:57 INFO - '_': '/tools/buildbot/bin/python'} 07:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:58 INFO - Downloading/unpacking flake8 07:07:58 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 07:07:58 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 07:07:58 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 07:07:58 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 07:07:58 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 07:07:58 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 07:07:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:07:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:07:59 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 07:07:59 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 07:07:59 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 07:07:59 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 07:07:59 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 07:07:59 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 07:08:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:08:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 07:08:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:08:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:08:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:08:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:08:00 INFO - no previously-included directories found matching 'docs/_build' 07:08:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:08:01 INFO - Running setup.py install for pep8 07:08:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:08:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:08:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:08:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:08:01 INFO - no previously-included directories found matching 'docs/_build' 07:08:01 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 07:08:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:08:01 INFO - Cleaning up... 07:08:01 INFO - Return code: 0 07:08:01 INFO - Running post-action listener: _resource_record_post_action 07:08:01 INFO - Running post-action listener: _start_resource_monitoring 07:08:01 INFO - Starting resource monitoring. 07:08:01 INFO - ##### 07:08:01 INFO - ##### Running install step. 07:08:01 INFO - ##### 07:08:01 INFO - Running pre-action listener: _resource_record_pre_action 07:08:01 INFO - Running main action method: install 07:08:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:08:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:08:02 INFO - Reading from file tmpfile_stdout 07:08:02 INFO - Detecting whether we're running mozinstall >=1.0... 07:08:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 07:08:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 07:08:04 INFO - Reading from file tmpfile_stdout 07:08:04 INFO - Output received: 07:08:04 INFO - Usage: mozinstall [options] installer 07:08:04 INFO - Options: 07:08:04 INFO - -h, --help show this help message and exit 07:08:04 INFO - -d DEST, --destination=DEST 07:08:04 INFO - Directory to install application into. [default: 07:08:04 INFO - "/builds/slave/test-pgo"] 07:08:04 INFO - --app=APP Application being installed. [default: firefox] 07:08:04 INFO - mkdir: /builds/slave/test-pgo/build/application 07:08:04 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'] 07:08:04 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 07:08:18 INFO - Reading from file tmpfile_stdout 07:08:18 INFO - Output received: 07:08:18 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 07:08:18 INFO - Running post-action listener: _resource_record_post_action 07:08:18 INFO - ##### 07:08:18 INFO - ##### Running run-tests step. 07:08:18 INFO - ##### 07:08:18 INFO - Running pre-action listener: _resource_record_pre_action 07:08:18 INFO - Running main action method: run_tests 07:08:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 07:08:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 07:08:18 INFO - Python 2.7.3 07:08:18 INFO - Return code: 0 07:08:18 INFO - grabbing minidump binary from tooltool 07:08:18 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')]} 07:08:18 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 0x1b99350>, '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 0x1b9a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b9a6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 07:08:18 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 07:08:18 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 07:08:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:08:18 INFO - Return code: 0 07:08:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 07:08:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 07:08:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 07:08:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 07:08:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 07:08:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 07:08:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 07:08:19 INFO - Return code: 0 07:08:19 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/1455107601/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 07:08:19 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/1455107601/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --webServer localhost 07:08:19 INFO - Using env: {'DISPLAY': ':0', 07:08:19 INFO - 'HOME': '/home/cltbld', 07:08:19 INFO - 'LANG': 'en_US.UTF-8', 07:08:19 INFO - 'LANGUAGE': 'en_US:en', 07:08:19 INFO - 'LOGNAME': 'cltbld', 07:08:19 INFO - 'MAIL': '/var/mail/cltbld', 07:08:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:08:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 07:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:19 INFO - 'MOZ_NO_REMOTE': '1', 07:08:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:19 INFO - 'NO_EM_RESTART': '1', 07:08:19 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:08:19 INFO - 'PWD': '/builds/slave/test-pgo', 07:08:19 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 07:08:19 INFO - 'SHELL': '/bin/bash', 07:08:19 INFO - 'SHLVL': '1', 07:08:19 INFO - 'TERM': 'linux', 07:08:19 INFO - 'TMOUT': '86400', 07:08:19 INFO - 'USER': 'cltbld', 07:08:19 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331', 07:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:19 INFO - '_': '/tools/buildbot/bin/python'} 07:08:19 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/1455107601/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost'] with output_timeout 3600 07:08:19 INFO - mozversion INFO | application_buildid: 20160210043321 07:08:19 INFO - mozversion INFO | application_changeset: 4febbf1c7a8e4e1f9436f77feab2a5f611fe6891 07:08:19 INFO - mozversion INFO | application_display_name: Nightly 07:08:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:08:19 INFO - mozversion INFO | application_name: Firefox 07:08:19 INFO - mozversion INFO | application_remotingname: firefox 07:08:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:08:19 INFO - mozversion INFO | application_vendor: Mozilla 07:08:19 INFO - mozversion INFO | application_version: 45.0 07:08:19 INFO - mozversion INFO | platform_buildid: 20160210043321 07:08:19 INFO - mozversion INFO | platform_changeset: 4febbf1c7a8e4e1f9436f77feab2a5f611fe6891 07:08:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:08:19 INFO - mozversion INFO | platform_version: 45.0 07:08:19 INFO - 2016-02-10 07:08:19,917 DEBUG : using testdate: 1455116899 07:08:19 INFO - 2016-02-10 07:08:19,917 DEBUG : actual date: 1455116899 07:08:19 INFO - 2016-02-10 07:08:19,927 INFO : starting webserver on 'localhost:45867' 07:08:19 INFO - 2016-02-10 07:08:19,928 INFO : Starting test suite talos-linux64-ix-069 07:08:19 INFO - 2016-02-10 07:08:19,928 INFO : Starting test dromaeo_css 07:08:19 INFO - 2016-02-10 07:08:19,928 DEBUG : operating with platform_type : linux_ 07:08:19 INFO - 2016-02-10 07:08:19,929 INFO : Initialising browser for dromaeo_css test... 07:08:19 INFO - 2016-02-10 07:08:19,946 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOV9Bq8/profile http://localhost:45867/getInfo.html 07:08:24 INFO - 2016-02-10 07:08:24,315 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:08:24 INFO - 2016-02-10 07:08:24,315 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:08:24 INFO - 2016-02-10 07:08:24,316 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:08:24 INFO - 2016-02-10 07:08:24,316 DEBUG : BROWSER_OUTPUT: __metrics 07:08:24 INFO - 2016-02-10 07:08:24,485 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] 07:08:25 INFO - 2016-02-10 07:08:25,673 INFO : Browser initialized. 07:08:25 INFO - 2016-02-10 07:08:25,674 INFO : Running cycle 1/1 for dromaeo_css test... 07:08:25 INFO - 2016-02-10 07:08:25,674 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOV9Bq8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:08:37 INFO - 2016-02-10 07:08:37,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 131960832 07:08:37 INFO - 2016-02-10 07:08:37,386 DEBUG : BROWSER_OUTPUT: 07:09:28 INFO - 2016-02-10 07:09:28,425 DEBUG : BROWSER_OUTPUT: 1455116968422 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] 07:11:36 INFO - 2016-02-10 07:11:36,453 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45867/tests/dromaeo/cssquery-ext.html) 07:11:37 INFO - 2016-02-10 07:11:37,901 DEBUG : BROWSER_OUTPUT: RSS: Main: 299298816 07:11:37 INFO - 2016-02-10 07:11:37,901 DEBUG : BROWSER_OUTPUT: 07:14:39 INFO - 2016-02-10 07:14:39,981 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/dromaeo/cssquery-ext.html (next: http://localhost:45867/tests/dromaeo/cssquery-jquery.html) 07:14:40 INFO - 2016-02-10 07:14:40,548 DEBUG : BROWSER_OUTPUT: RSS: Main: 214999040 07:14:40 INFO - 2016-02-10 07:14:40,548 DEBUG : BROWSER_OUTPUT: 07:17:42 INFO - 2016-02-10 07:17:42,283 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45867/tests/dromaeo/cssquery-mootools.html) 07:17:42 INFO - 2016-02-10 07:17:42,856 DEBUG : BROWSER_OUTPUT: RSS: Main: 213778432 07:17:42 INFO - 2016-02-10 07:17:42,857 DEBUG : BROWSER_OUTPUT: 07:20:44 INFO - 2016-02-10 07:20:44,538 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45867/tests/dromaeo/cssquery-prototype.html) 07:20:45 INFO - 2016-02-10 07:20:45,106 DEBUG : BROWSER_OUTPUT: RSS: Main: 197398528 07:20:45 INFO - 2016-02-10 07:20:45,106 DEBUG : BROWSER_OUTPUT: 07:23:47 INFO - 2016-02-10 07:23:47,468 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45867/tests/dromaeo/cssquery-yui.html) 07:23:48 INFO - 2016-02-10 07:23:48,042 DEBUG : BROWSER_OUTPUT: RSS: Main: 198189056 07:23:48 INFO - 2016-02-10 07:23:48,042 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/dromaeo/cssquery-yui.html (next: http://localhost:45867/tests/dromaeo/cssquery-dojo.html) 07:26:50 INFO - 2016-02-10 07:26:50,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 241143808 07:26:50 INFO - 2016-02-10 07:26:50,538 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,539 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:26:50 INFO - 2016-02-10 07:26:50,539 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:26:50 INFO - 2016-02-10 07:26:50,539 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:26:50 INFO - 2016-02-10 07:26:50,540 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:26:50 INFO - 2016-02-10 07:26:50,541 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3264.6572109928456;3314.370269648765;3324.1024923270716;3326.7671263011584;3345.514900339451;5418.810341638038;5448.046591846431;5457.536109430698;5468.425815289397;5479.123340265553;5454.427285135063;5455.5908306877045;5472.753726082326;5492.395836457997;5501.807436739705;4875.804967801284;4950.430700469438;4988.551030407262;5057;5069.44236134025;4889.33993910821;5039.546440820325;5058.822941197059;5075.314832497603;5131.666441681297;4973.552380285773;5039.546440820325;5051.722155281458;5056.8482945511505;5081.923771143417;5047.545720885119;5081.441041485434;5106.66806657566;5110.66780659256;5126.051680439094;4830.830920917786;4833.879153021167;4934.506549345073;5007.198848184273;5008.12357837377;5234.8691282717855;5250.44870288618;5265.894682106336;5270.5520030797425;5293.285406470116;5880.26496687914;5911.261092363455;5929.436703513159;5930.55520835939;5934.495567876736;5771.942280577225;5842.766289348463;5844.824655260349;5863.355030946593;5876.0010798163985;5537.031019571559;5595.804146854841;5600.187972743929;5607.719614019324;5615.326160860641;6081.513478921675;6085.57400981927;6092.116643086728;6095.451409373178;6100.481459076028;5631.239782629293;5677.545796336283;5727.8568035799435;5798.565107616945;5823.475887170091;5883.264591926009;5956.910646340309;5958.2850057992455;5967.850803729854;5968.522518198533;5721.771129154788;5880.411958804086;5922.585419020627;5927.170196172539;5934.080217566284;5863.736131874076;5902.144189092557;5990.550708696778;6002.039673652194;6038.275406951107;4546.909061818745;4553.886152846139;4577.633789296849;4583.74789386587;4617.515160908123;3925.941110883339;4065.2072845794787;4115.773632450184;4127.9587204128175;4165.354370072643;5426;5481.150421684645;5481.342238931354;5501.229827824108;5538.6399884007415;5932;5971.28344598651;5977.282726072899;5990.28116625999;5992.310884248367;5365.3293338332705;5410.215518749759;5461.371942226615;5582.748776304995;5590.217369568264;5086.287919691246;5124.743762811919;5166.560842328444;5173.5343819055715;5180.481951804864;6103.908441373383;6112.908306375408;6121.693915304217;6127.142200091991;6134.38656134383;5752.712364381848;5786.855328616818;5877.529797616266;5944.227250457413;5966.522678185822;6043.607165534227;6045.667488288011;6047.0627052806885;6051.243594550681;6093.086037094471;5764.798232061858;5766.682832444256;5789.681567513659;5798.043322851682;5809.157672137432;5641.323041235078;5733.340665823317;5756.481916627356;5918.704064796657;5922.289325280994;5654.151877218285;5664.207011018543;5689.374168841341;5695.800646977336;5695.943040569541;5653.491185793298;5665.66006039639;5675.574331925205;5677.659340439587;5689.061304884648;5574.637648552831;5578.470045345685;5581.525570326406;5584.888302234014;5659.320881494247;5731.312242530923;5767.365589785204;5786.016377215799;5787.131930210502;5830.5627077968475;5661.773529058792;5677.7161141941915;5680;5682.6306290091015;5684.8010319638615 07:26:50 INFO - 2016-02-10 07:26:50,542 DEBUG : BROWSER_OUTPUT: |1;ext.html;551.114932869511;588.661519626215;593.2406519654842;631.3560168627997;642.1042645509511;33257;41485.79257103714;42403;42438.78780606093;42470.362944555854;46731.53268467314;47081.29378059332;47135.528644713544;47247.52752472474;47265.52734472654;8175.1416101309105;8296.419250652456;9092.045335239787;9196.034416386283;9270.119338662817;10683.78632427351;10990.395528245943;11011.944940275287;11027.062699670538;11029.44852757363;10712.625058122967;10811.945940270287;10825.350478971248;10826.566937322497;10829.29609575379;8429.32565394767;8674.522901240429;10464.476776161162;10663.413512256822;10753.892461075446;10457.633982810565;10615.628453004185;10637.57449702011;10646.787064258671;10653.627123050734;43433.782831085955;43807.561924380985;43808;43893.341599876025;44051.77974110141;3625.474306225609;3653.2328211075705;3699.8890033299044;4123;4311.059690449827;32695;32971.340573188405;33269;33321.33357332864;33394.1651458713;4310;4312.5903039211225;4382.298832186835;4396.120775844812;4426.62373698236;301398;302376;302706;303774.4811275952;304367;63984;64526.677366612865;64551.35448645453;64568.677156613914;64578.677106615105;67572.662136689;67692.3230767696;67737;67786;67821.66089169522;15656.37374505007;15973.440929567641;16056.27746751399;16077.196140192944;16205;12832.358382080858;12863.614091577268;12867.42096605655;12876.098673092794;12920;20286.898565507374;20596.27913023037;20602.484937876674;20657.690134647994;20658.58682826365;7731.729389471341;7786.18245084258;7809.258120478659;7863.803404914808;7907.683692652229;1010.8433192855119;1106.397013627575;1108.772701596171;1123.7977164110375;1138.8291756236465;30241.848790755907;30418.84790576033;30718.38563228723;32834.17914552155;33221.236727045594;1057.513543769859;1124.8481455003634;1125.5497800879714;1203.6990752311922;1276.113101394522;13946.442142314396;14133.929330353283;14313.28433578317;15901.125438101015;16093.839061609468;337588;346464;346638;346683;346986;55584;55754;56007.43992560104;56126.43873561294;56199;1449.1812126148693;1459.4016453254328;1460.664047269134;1468.9118652880861;1498.4380857178558;29874.103776886728;30140.24649383783;30747.231319217197;31043.68956310408;31176.53235201519;49138.26292605685;49489.75255123701;49594.752026239636;49609.50390496049;49632.75183624203;318272;321253;321402;321582;322194;30312.09063728092;30836.22909427282;34034.489482658275;34322.313553729284;34393;42079.158416832106;43285.78357108194;43408.782956085015;44404.11191776211;44411.11177776491;36363;36611;37203.441948371335;37555.81222093872;37995.43006854955;37087.25825483421;37218.62781372152;37885.81057094697;37982.81008594939;38144.61855381411;36273.45589816208;36980.81509592435;37278.627213727515;37289.81355093207;37415;32985.83507082545;33641;34355.65644343525;35049.123771905906;36870.8156459216;26301;26681.7331826687;26820.32949176208;26842.731572684024;26895.549373436057 07:26:50 INFO - 2016-02-10 07:26:50,544 DEBUG : BROWSER_OUTPUT: |2;jquery.html;354.68610279902293;360.117711606564;360.52410817720613;361.49751844935537;366.1012215012145;15379.38482460703;15819.762703559456;17265.827341726577;17813.732794008083;18421.078946052694;18455.723164152514;18546.814531854678;18761.718574221362;18939.33712320069;18994.335198268065;5540.944590554093;5685.862827434509;5687.146927960818;5690.7723691052515;5693.829185124432;4272.722273052257;4395.142947125312;5640.210370548127;6060.424259695322;6208.627482351073;6054.409275684596;6071.301800292957;6111.358307377728;6136.263648362203;6140.570160088796;6095.756169753205;6115.266168059839;6126.387361263838;6133.570650054498;6146.078088286748;5237.266782650432;5261.710605916673;5266.104762190437;5283.841484755463;5304.92042619361;22151.33545993623;22240;22384.88807555968;22641.320760377213;22743.431414214778;2361.6103342948395;2373.4541055557147;2377.322463098026;2382.845115067515;2388.522295540899;13911.373988170557;14033.5088271911;14203.289835508287;14265.928670356685;14501.202433866134;1851.7407562941198;1916.4154932745507;1934.4486821256016;1937.94186174415;2004.8271761019676;61526.69236653833;64315.67842160759;64494.355056449305;64769.67615161941;64889.3511064888;9420.764480887896;9740;10012.69961901144;10025.699229023141;10049.095581397709;13010.089293749535;13013.739725205443;13020.674483137997;13046;13060.151090179108;7332.450066245053;7370.852582948311;7536.5101268417375;7618.771436856903;7622.466427350032;6983.266756990544;7005.92994070063;7036.240939765059;7036.753713619999;7037.894431583531;6098.0852872069545;6128.417800308964;6164.506839452833;6199.132121502993;6233.472799164234;1581.7073841339388;1666.4417420164139;1671.1059583123215;1674.0625249860118;1682.6466442047308;458.53458739379556;1014.7310962594918;1085.625459216569;1172.7068232941765;1185.6739396665987;10597.894021059845;10933.125349971982;10974.780504389868;11072.889271107348;11101;451.1563376485927;453.5441880909665;456.1629410032561;456.8058575105567;472.1925507382346;5893.821235752866;6035.366286539938;6068.302145253264;6074.787382441594;6079.483243924227;401000;632527;638199;646851;650387;43885.341719874225;56113.438865611635;70470.29529704637;77507.61246193733;77537.22462775413;464.55170760216555;469.7275580163497;470.5177193376816;471.12257653251766;471.5284715284715;10220.488975551341;10397.064264216257;10486.37081775096;10495.632652857113;10514.474276286308;35796.463053054744;35982.82008590045;36437.63562364342;37627.81186094052;37849;47040.529594703614;49604.25593616171;49918;51804.740976294874;53197.734011329696;9232.861507077265;9559.091886270793;9665.420074795535;9880.45657488845;9983.800323993624;13513.391897364445;17803.19885605125;18676.34632787846;18971.051447427297;19159.233630654617;15376.923115384352;15423.305951232445;15497.14765687898;15567.922160389126;15650.060996340257;2934.1784300395925;14472.335681396353;14710.852891471377;14807.703845922806;15362.231888405311;15255.389784408499;15855.920720396323;15910.284037218122;16118.516444506684;16237.269322880262;9613.567389467351;9614.663486777928;9694.321397502106;9730.805383892424;9741.853872191781;11527.365994870363;15848.762268566212;15993.360265589246;16012.679746404774;16209.513714588582 07:26:50 INFO - 2016-02-10 07:26:50,547 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1539.5766164304807;1731.48055583325;1801.7657704498413;1815.4644379907925;1839.9264029438823;7287.963560182198;7573.394128469723;7573.6970521179155;7584.4690871639;7584.507007044538;6787.287334829846;6967.094277743886;7289.890651640217;7475.2151024142495;7477.1775104738435;2107.9999999999964;2124.8193903518218;2141.175647375756;2141.550274442361;2147.967780483294;2012.0543344628074;2253.43664083979;2278.054607337953;2308.0652335804025;2311.4914718761847;2019.2730616977876;2201.2625770366867;2256.3005468304796;2257.3340864445026;2261.027758064032;2162.4485756132244;2188.0153930731235;2193.8683678979314;2196.5936301784222;2198.7801219877965;2273.4202778291597;2281.190177486985;2306.8269879759087;2308.4459729664927;2309.399556115422;7488.4009279257525;7537.811554711121;7549.50928189666;7555.055618047744;7559.58422286774;4554.795034223468;4610.031893302411;4619.653525985531;4622.098690755304;4623.884402889921;2939.955900661492;2960.985195074032;3008.533677280025;3011.6837732038257;3023.727864492206;926.9304802139799;938.2634631813993;977.154761131616;993.8409854423257;994.9005099490065;10414.687559373308;10430.530626121767;10433.634822781167;10438.843417348746;10450.268481206318;3875.0312421894528;3906.277338692351;3909.081365879016;3922.4116382542848;3966.2662407454714;9006.144416280444;9012.729618111467;9014.323925705598;9014.819703606023;9022.413543119676;4482.8206871725415;4495.393121928563;4503.279475284005;4520.81916723334;4526.705764125321;4129.855455059059;4135.697255364551;4137.565555616678;4138.668906487474;4139.81370838313;3550.928981420409;3584.605693373727;3613.3495970725;3613.9277214455565;3623.4564815277918;1027.9023492768174;1028.341861208827;1071.8499410082595;1079.8164312066967;1080.983785243222;1108.2463924531235;1128.5824245029391;1163.0521125282867;1168.1705988748083;1204.2112416367293;7389.7413590524075;7428.1457632371885;7496.437767167376;7520.247975202436;7530.6611202496015;1174.6123779152858;1192.5289510643247;1197.0423661071281;1200.765850659113;1220.7192345760525;5881.705914704247;5939.910901336483;5939.910901336483;5940.227770389822;5943.10853371989;8579.914200858037;9049.09509049103;9135;9144.954275228582;9181.58682859273;9073.183413492818;9077.818443631091;9080.046595107551;9087.409318394282;9117.680881169128;6620.933790662128;6874.106366172366;6946.7221311148;6963.54736942087;7078.929210707827;7299.197088320387;7472.925270747223;7479.813004674709;7486.663100160615;7488.438367122379;7221.819454513679;7358.080239970004;7474.215207403145;7527.435442341955;7548.924510754822;8353.832923341377;8415;8418.158184181533;8430.6206220719;8439.873401898854;6734.4612431004425;6924.93075069263;6928.307169283233;6949.3745562899585;6973.19808222061;7451.105867295742;7495.475316727995;7498;7508.211637777955;7511.661975211238;5973.970130149321;6772.932270677427;6965.199002114619;7059.611721355374;7184.353408193288;6820.215675197217;6993.055937448379;7029.578225306498;7034.648267586498;7129.607871567114;6920.134983127109;7071.398931090709;7077.5753454792875;7079.929200708133;7081.079459670416;6366.840828979312;6391.840203994937;6392.744290228339;6394.616323020634;6398.680065996775;6249.906251406142;6275.560710750204;6338.11266422692;6447.3552644735155;6649.867002660017 07:26:50 INFO - 2016-02-10 07:26:50,547 DEBUG : BROWSER_OUTPUT: |4;prototype.html;344.5090745687395;353.344545867416;353.5050928699821;355.04669961154286;357.7638758419442;5982.491488223501;6053.061775424816;6058.878822423549;6069.302030266521;6071.058985857188;5716.7141642917895;5806.9709651451685;5892.587518873681;5989.580729348947;5999.730012149453;4612.607928326096;4633;4640.280756482751;4645.651576131787;4651.488336283014;4411.713238639494;4646.186917289478;4653;4653.092646933849;4658.301254811771;4468.8882777930485;4469.776511174445;4585.326355880096;4598.287265473857;4602.792874320664;4569.611583015431;4594.632429405656;4606.9078618427775;4610.65420093491;4623.514530974233;4068.674506039539;4071.918561628751;4073.8370465181356;4074.6129117733767;4074.877753667365;5561.916571251434;5666.886662266732;5668;5670.914936275959;5676.2620859288445;5311.548518375943;5327.094393952999;5329.706866122362;5329.813456529003;5332.893342133174;6373.776917807854;6380.840478988062;6392.968035159841;6397.232332120128;6399.872002559922;4407.493138289105;4418.624416924566;4421.182081314967;4421.889452763675;4435.844745433927;6501.5773974691965;6553.541252112354;6614.834629134262;6616.8089743846085;6620.404163625297;4506.481754598198;4507.143642707875;4511.571400716926;4518.051209246029;4518.141553104965;4557.248054071108;4557.840475583405;4559.6124329431705;4569.954300456953;4573.519780423074;5271.525562699376;5288.233206185082;5289.2859463972645;5294.8941021180135;5299.4435584263865;6292.8426789329715;6397.6801159941815;6408.455281301049;6409.935900641027;6410.391012853772;1590.8886377953431;1609.9678006439806;1610.645657955248;1611.9119594273818;1614.1122382689473;1061.1881910338598;1071.796358691846;1074.3553867679486;1077.3482043363651;1092.759592889563;6082.9695851520455;6121.418465245794;6122.846928826726;6128.142060111588;6128.601640893327;6391.169148010729;6597.208334999831;6599.274079851309;6605.5376123670885;6614.040964060185;6059.666718330446;6082.422169893853;6179.876402472015;6189.102580125857;6195.442410183015;3529.8941031768572;3581.462780582933;3584.874529391459;3586.0676224181902;3588.4796704478226;6642.800715978528;6860;6862.759803406857;6864.5881247125335;6872.209695885037;6761.8647627047185;6792.592444453348;6798.490113241527;6803.65981700913;6809.557378770364;6840.657967101625;6844.589324640538;6857.27998560154;6860.279670634612;6862.4853136015;6427.710753016032;6633.170853643294;6637.435817955335;6642.368974947372;6646;6598.274189839018;6605.471562275103;6613.503987200883;6622;6622;6871.14110736158;6876;6876.346747059021;6880.105586273752;6890.517663763489;4473.373727678063;4500.819967201275;4503.189425903238;4506.053728717006;4508.549145085465;6521.250056243593;6711.664416779044;6720.462363011045;6721;6722.731090756511;6482.837929051623;6511.120998665119;6514.348565143448;6533.67331633407;6545.050967609765;6310.7791227306825;6453.741850326122;6496.285408605144;6509;6509.772157974448;6335.334789847;6336.873262534816;6347.5556711029785;6347.8095657128515;6348.460380867678;6459.257185423737;6466.256380516113;6468.320826313169;6543.345665433419;6544.214694236532;6245.594036387621;6362.1729175208775;6367.490600751836;6376.39424254695;6377.1390862233 07:26:50 INFO - 2016-02-10 07:26:50,551 DEBUG : BROWSER_OUTPUT: |5;yui.html;927.92112670423;938.648006997376;938.7043081429354;939.4363381970818;955.8052434456929;14145.929270353647;14417.351219195136;14425.278736063206;14432.350544225508;14436.927815360908;13608.455661773542;13647.522336718217;13685.247311397869;13739.793903091437;13745.312734363268;769.9653515591812;775.9961200193991;776.0919723922996;895.4850960697609;915.7115508614799;888.7022847346146;905.3481493324796;905.3888625178012;905.3888625178012;905.4522014181424;951.1154626197633;958.4967891856761;959.2374062620199;960.1646567486262;960.6781728121053;810.5055915891331;815.2214635023538;940.83535381308;948.7438391634255;951.9666811661593;711.8042538302009;717.426059152681;718.0234880562431;718.105958082191;719.2663483247111;13703.657408564766;13779.655508612264;13837.861621383758;13919.164850108924;13959.441622335095;12243.632691019284;12946.805797913039;13042.739145217138;13465.192088474723;13517.053806233567;10666.3066900652;10730.30253033558;10853.565857365775;10956.397398143099;11182.105431565455;3906.140649057203;3928.489296391451;3930.921381572381;3957.861474848395;3985.880423587297;46007.539924600664;46318.5368146321;46481.76759116183;46893.76553117247;46982.06035879297;10887.782244355069;11010.39442830636;11052.834207486894;11086.057685096857;11221.66335009951;16393.508194754177;16477.423290184786;16508.092054936853;16516.17419129039;16747.581310467336;7862.803429914184;7905.960470197612;8003.239692229229;8407.411481196257;8483.575821208915;4667.766611669403;4682.2508398656055;4698.060387922429;4753.04939012199;4762.9285560715925;3172.0959526534825;3195.888143914952;3197.4884018557386;3202.5676533668125;3205.3589282143666;579.2325169150807;592.2655906675691;597.6175247841385;600.6756351570191;605.3946053946054;650.4276236911583;731.4075598765018;735.4667865797254;753.284379839155;771.1517330936038;9293.256539476826;9431.104045115702;9462.621495140118;9547.713568592953;9614.855777163348;599.2120361724319;650.5413683353224;652.4714980865514;676.9936320906866;758.518340853562;4316.784160792011;4387.4954380246045;4480.283154695233;4519.638428925678;4537.636989040869;56759;57541;57847.421525785045;58113;58315.708421457624;52024;57000;57092.143617845766;57735.71132144312;58253.70873145607;46499;47438;47681;47882;48145;9049.864252036356;9297.30270229722;9656.27577931672;9845.45849978258;10056.547455364673;36024.459633106046;37044.814775925945;37582.62417375791;37754;39109;43691.78154109336;44067.55932440634;44136.55863441453;44914.77542612266;45624;11674.241174323643;11893.167478276784;12427.565035223724;12462.189957652723;12827.935860320638;12901.161424507378;13611.659708507366;13644.386002629706;13831.585052448443;14592.197429141499;11617.825732614187;12206.389680515762;12323.815142772686;12692.746145076862;13847.23840188759;12280.75438491243;12286.569970051005;12701.301428421526;12875.163114397534;12884.420201091161;12441.264257276285;12909.160904541175;12939.288339141438;12971.740565188833;13374.665633359244;10264;10652.307600005975;10751.19366047534;10811.405372704263;11079.169062320198;10533.525991330256;10826.621068262888;10842.241043126905;10889.183311251847;10974.286671366335 07:26:50 INFO - 2016-02-10 07:26:50,551 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:26:50 INFO - 2016-02-10 07:26:50,551 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:26:50 INFO - 2016-02-10 07:26:50,551 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1189 07:26:50 INFO - 2016-02-10 07:26:50,551 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:26:50 INFO - 2016-02-10 07:26:50,551 DEBUG : BROWSER_OUTPUT: __startTimestamp1455118010538__endTimestamp 07:26:50 INFO - 2016-02-10 07:26:50,551 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5464.18 Median:5654.15 stddev:597.40 (10.6%) stddev-sans-first:573.77 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: Values: 3264.7 3314.4 3324.1 3326.8 3345.5 5418.8 5448.0 5457.5 5468.4 5479.1 5454.4 5455.6 5472.8 5492.4 5501.8 4875.8 4950.4 4988.6 5057.0 5069.4 4889.3 5039.5 5058.8 5075.3 5131.7 4973.6 5039.5 5051.7 5056.8 5081.9 5047.5 5081.4 5106.7 5110.7 5126.1 4830.8 4833.9 4934.5 5007.2 5008.1 5234.9 5250.4 5265.9 5270.6 5293.3 5880.3 5911.3 5929.4 5930.6 5934.5 5771.9 5842.8 5844.8 5863.4 5876.0 5537.0 5595.8 5600.2 5607.7 5615.3 6081.5 6085.6 6092.1 6095.5 6100.5 5631.2 5677.5 5727.9 5798.6 5823.5 5883.3 5956.9 5958.3 5967.9 5968.5 5721.8 5880.4 5922.6 5927.2 5934.1 5863.7 5902.1 5990.6 6002.0 6038.3 4546.9 4553.9 4577.6 4583.7 4617.5 3925.9 4065.2 4115.8 4128.0 4165.4 5426.0 5481.2 5481.3 5501.2 5538.6 5932.0 5971.3 5977.3 5990.3 5992.3 5365.3 5410.2 5461.4 5582.7 5590.2 5086.3 5124.7 5166.6 5173.5 5180.5 6103.9 6112.9 6121.7 6127.1 6134.4 5752.7 5786.9 5877.5 5944.2 5966.5 6043.6 6045.7 6047.1 6051.2 6093.1 5764.8 5766.7 5789.7 5798.0 5809.2 5641.3 5733.3 5756.5 5918.7 5922.3 5654.2 5664.2 5689.4 5695.8 5695.9 5653.5 5665.7 5675.6 5677.7 5689.1 5574.6 5578.5 5581.5 5584.9 5659.3 5731.3 5767.4 5786.0 5787.1 5830.6 5661.8 5677.7 5680.0 5682.6 5684.8 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50498.25 Median:34338.98 stddev:84527.66 (246.2%) stddev-sans-first:84681.59 07:26:50 INFO - 2016-02-10 07:26:50,552 DEBUG : BROWSER_OUTPUT: Values: 551.1 588.7 593.2 631.4 642.1 33257.0 41485.8 42403.0 42438.8 42470.4 46731.5 47081.3 47135.5 47247.5 47265.5 8175.1 8296.4 9092.0 9196.0 9270.1 10683.8 10990.4 11011.9 11027.1 11029.4 10712.6 10811.9 10825.4 10826.6 10829.3 8429.3 8674.5 10464.5 10663.4 10753.9 10457.6 10615.6 10637.6 10646.8 10653.6 43433.8 43807.6 43808.0 43893.3 44051.8 3625.5 3653.2 3699.9 4123.0 4311.1 32695.0 32971.3 33269.0 33321.3 33394.2 4310.0 4312.6 4382.3 4396.1 4426.6 301398.0 302376.0 302706.0 303774.5 304367.0 63984.0 64526.7 64551.4 64568.7 64578.7 67572.7 67692.3 67737.0 67786.0 67821.7 15656.4 15973.4 16056.3 16077.2 16205.0 12832.4 12863.6 12867.4 12876.1 12920.0 20286.9 20596.3 20602.5 20657.7 20658.6 7731.7 7786.2 7809.3 7863.8 7907.7 1010.8 1106.4 1108.8 1123.8 1138.8 30241.8 30418.8 30718.4 32834.2 33221.2 1057.5 1124.8 1125.5 1203.7 1276.1 13946.4 14133.9 14313.3 15901.1 16093.8 337588.0 346464.0 346638.0 346683.0 346986.0 55584.0 55754.0 56007.4 56126.4 56199.0 1449.2 1459.4 1460.7 1468.9 1498.4 29874.1 30140.2 30747.2 31043.7 31176.5 49138.3 49489.8 49594.8 49609.5 49632.8 318272.0 321253.0 321402.0 321582.0 322194.0 30312.1 30836.2 34034.5 34322.3 34393.0 42079.2 43285.8 43408.8 44404.1 44411.1 36363.0 36611.0 37203.4 37555.8 37995.4 37087.3 37218.6 37885.8 37982.8 38144.6 36273.5 36980.8 37278.6 37289.8 37415.0 32985.8 33641.0 34355.7 35049.1 36870.8 26301.0 26681.7 26820.3 26842.7 26895.5 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30161.61 Median:37738.41 stddev:98191.49 (260.2%) stddev-sans-first:98441.43 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: Values: 354.7 360.1 360.5 361.5 366.1 15379.4 15819.8 17265.8 17813.7 18421.1 18455.7 18546.8 18761.7 18939.3 18994.3 5540.9 5685.9 5687.1 5690.8 5693.8 4272.7 4395.1 5640.2 6060.4 6208.6 6054.4 6071.3 6111.4 6136.3 6140.6 6095.8 6115.3 6126.4 6133.6 6146.1 5237.3 5261.7 5266.1 5283.8 5304.9 22151.3 22240.0 22384.9 22641.3 22743.4 2361.6 2373.5 2377.3 2382.8 2388.5 13911.4 14033.5 14203.3 14265.9 14501.2 1851.7 1916.4 1934.4 1937.9 2004.8 61526.7 64315.7 64494.4 64769.7 64889.4 9420.8 9740.0 10012.7 10025.7 10049.1 13010.1 13013.7 13020.7 13046.0 13060.2 7332.5 7370.9 7536.5 7618.8 7622.5 6983.3 7005.9 7036.2 7036.8 7037.9 6098.1 6128.4 6164.5 6199.1 6233.5 1581.7 1666.4 1671.1 1674.1 1682.6 458.5 1014.7 1085.6 1172.7 1185.7 10597.9 10933.1 10974.8 11072.9 11101.0 451.2 453.5 456.2 456.8 472.2 5893.8 6035.4 6068.3 6074.8 6079.5 401000.0 632527.0 638199.0 646851.0 650387.0 43885.3 56113.4 70470.3 77507.6 77537.2 464.6 469.7 470.5 471.1 471.5 10220.5 10397.1 10486.4 10495.6 10514.5 35796.5 35982.8 36437.6 37627.8 37849.0 47040.5 49604.3 49918.0 51804.7 53197.7 9232.9 9559.1 9665.4 9880.5 9983.8 13513.4 17803.2 18676.3 18971.1 19159.2 15376.9 15423.3 15497.1 15567.9 15650.1 2934.2 14472.3 14710.9 14807.7 15362.2 15255.4 15855.9 15910.3 16118.5 16237.3 9613.6 9614.7 9694.3 9730.8 9741.9 11527.4 15848.8 15993.4 16012.7 16209.5 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5342.43 Median:6392.29 stddev:2748.64 (43.0%) stddev-sans-first:2741.49 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: Values: 1539.6 1731.5 1801.8 1815.5 1839.9 7288.0 7573.4 7573.7 7584.5 7584.5 6787.3 6967.1 7289.9 7475.2 7477.2 2108.0 2124.8 2141.2 2141.6 2148.0 2012.1 2253.4 2278.1 2308.1 2311.5 2019.3 2201.3 2256.3 2257.3 2261.0 2162.4 2188.0 2193.9 2196.6 2198.8 2273.4 2281.2 2306.8 2308.4 2309.4 7488.4 7537.8 7549.5 7555.1 7559.6 4554.8 4610.0 4619.7 4622.1 4623.9 2940.0 2961.0 3008.5 3011.7 3023.7 926.9 938.3 977.2 993.8 994.9 10414.7 10430.5 10433.6 10438.8 10450.3 3875.0 3906.3 3909.1 3922.4 3966.3 9006.1 9012.7 9014.3 9014.8 9022.4 4482.8 4495.4 4503.3 4520.8 4526.7 4129.9 4135.7 4137.6 4138.7 4139.8 3550.9 3584.6 3613.3 3613.9 3623.5 1027.9 1028.3 1071.8 1079.8 1081.0 1108.2 1128.6 1163.1 1168.2 1204.2 7389.7 7428.1 7496.4 7520.2 7530.7 1174.6 1192.5 1197.0 1200.8 1220.7 5881.7 5939.9 5939.9 5940.2 5943.1 8579.9 9049.1 9135.0 9145.0 9181.6 9073.2 9077.8 9080.0 9087.4 9117.7 6620.9 6874.1 6946.7 6963.5 7078.9 7299.2 7472.9 7479.8 7486.7 7488.4 7221.8 7358.1 7474.2 7527.4 7548.9 8353.8 8415.0 8418.2 8430.6 8439.9 6734.5 6924.9 6928.3 6949.4 6973.2 7451.1 7495.5 7498.0 7508.2 7511.7 5974.0 6772.9 6965.2 7059.6 7184.4 6820.2 6993.1 7029.6 7034.6 7129.6 6920.1 7071.4 7077.6 7079.9 7081.1 6366.8 6391.8 6392.7 6394.6 6398.7 6249.9 6275.6 6338.1 6447.4 6649.9 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5356.22 Median:6076.74 stddev:1621.57 (26.7%) stddev-sans-first:1581.89 07:26:50 INFO - 2016-02-10 07:26:50,553 DEBUG : BROWSER_OUTPUT: Values: 344.5 353.3 353.5 355.0 357.8 5982.5 6053.1 6058.9 6069.3 6071.1 5716.7 5807.0 5892.6 5989.6 5999.7 4612.6 4633.0 4640.3 4645.7 4651.5 4411.7 4646.2 4653.0 4653.1 4658.3 4468.9 4469.8 4585.3 4598.3 4602.8 4569.6 4594.6 4606.9 4610.7 4623.5 4068.7 4071.9 4073.8 4074.6 4074.9 5561.9 5666.9 5668.0 5670.9 5676.3 5311.5 5327.1 5329.7 5329.8 5332.9 6373.8 6380.8 6393.0 6397.2 6399.9 4407.5 4418.6 4421.2 4421.9 4435.8 6501.6 6553.5 6614.8 6616.8 6620.4 4506.5 4507.1 4511.6 4518.1 4518.1 4557.2 4557.8 4559.6 4570.0 4573.5 5271.5 5288.2 5289.3 5294.9 5299.4 6292.8 6397.7 6408.5 6409.9 6410.4 1590.9 1610.0 1610.6 1611.9 1614.1 1061.2 1071.8 1074.4 1077.3 1092.8 6083.0 6121.4 6122.8 6128.1 6128.6 6391.2 6597.2 6599.3 6605.5 6614.0 6059.7 6082.4 6179.9 6189.1 6195.4 3529.9 3581.5 3584.9 3586.1 3588.5 6642.8 6860.0 6862.8 6864.6 6872.2 6761.9 6792.6 6798.5 6803.7 6809.6 6840.7 6844.6 6857.3 6860.3 6862.5 6427.7 6633.2 6637.4 6642.4 6646.0 6598.3 6605.5 6613.5 6622.0 6622.0 6871.1 6876.0 6876.3 6880.1 6890.5 4473.4 4500.8 4503.2 4506.1 4508.5 6521.3 6711.7 6720.5 6721.0 6722.7 6482.8 6511.1 6514.3 6533.7 6545.1 6310.8 6453.7 6496.3 6509.0 6509.8 6335.3 6336.9 6347.6 6347.8 6348.5 6459.3 6466.3 6468.3 6543.3 6544.2 6245.6 6362.2 6367.5 6376.4 6377.1 07:26:50 INFO - 2016-02-10 07:26:50,554 DEBUG : BROWSER_OUTPUT: 07:26:50 INFO - 2016-02-10 07:26:50,554 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14440.77 Median:24308.42 stddev:16264.31 (66.9%) stddev-sans-first:16278.28 07:26:50 INFO - 2016-02-10 07:26:50,554 DEBUG : BROWSER_OUTPUT: Values: 927.9 938.6 938.7 939.4 955.8 14145.9 14417.4 14425.3 14432.4 14436.9 13608.5 13647.5 13685.2 13739.8 13745.3 770.0 776.0 776.1 895.5 915.7 888.7 905.3 905.4 905.4 905.5 951.1 958.5 959.2 960.2 960.7 810.5 815.2 940.8 948.7 952.0 711.8 717.4 718.0 718.1 719.3 13703.7 13779.7 13837.9 13919.2 13959.4 12243.6 12946.8 13042.7 13465.2 13517.1 10666.3 10730.3 10853.6 10956.4 11182.1 3906.1 3928.5 3930.9 3957.9 3985.9 46007.5 46318.5 46481.8 46893.8 46982.1 10887.8 11010.4 11052.8 11086.1 11221.7 16393.5 16477.4 16508.1 16516.2 16747.6 7862.8 7906.0 8003.2 8407.4 8483.6 4667.8 4682.3 4698.1 4753.0 4762.9 3172.1 3195.9 3197.5 3202.6 3205.4 579.2 592.3 597.6 600.7 605.4 650.4 731.4 735.5 753.3 771.2 9293.3 9431.1 9462.6 9547.7 9614.9 599.2 650.5 652.5 677.0 758.5 4316.8 4387.5 4480.3 4519.6 4537.6 56759.0 57541.0 57847.4 58113.0 58315.7 52024.0 57000.0 57092.1 57735.7 58253.7 46499.0 47438.0 47681.0 47882.0 48145.0 9049.9 9297.3 9656.3 9845.5 10056.5 36024.5 37044.8 37582.6 37754.0 39109.0 43691.8 44067.6 44136.6 44914.8 45624.0 11674.2 11893.2 12427.6 12462.2 12827.9 12901.2 13611.7 13644.4 13831.6 14592.2 11617.8 12206.4 12323.8 12692.7 13847.2 12280.8 12286.6 12701.3 12875.2 12884.4 12441.3 12909.2 12939.3 12971.7 13374.7 10264.0 10652.3 10751.2 10811.4 11079.2 10533.5 10826.6 10842.2 10889.2 10974.3 07:26:50 INFO - 2016-02-10 07:26:50,554 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:26:50 INFO - 2016-02-10 07:26:50,554 DEBUG : BROWSER_OUTPUT: 07:26:51 INFO - 2016-02-10 07:26:51,121 INFO : Browser exited with error code: 0 07:26:51 INFO - 2016-02-10 07:26:51,142 INFO : Completed test dromaeo_css (00:18:31) 07:26:51 INFO - 2016-02-10 07:26:51,142 INFO : Starting test kraken 07:26:51 INFO - 2016-02-10 07:26:51,142 DEBUG : operating with platform_type : linux_ 07:26:51 INFO - 2016-02-10 07:26:51,142 INFO : Initialising browser for kraken test... 07:26:51 INFO - 2016-02-10 07:26:51,148 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUNfS2q/profile http://localhost:45867/getInfo.html 07:26:55 INFO - 2016-02-10 07:26:55,431 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:26:55 INFO - 2016-02-10 07:26:55,432 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:26:55 INFO - 2016-02-10 07:26:55,432 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:26:55 INFO - 2016-02-10 07:26:55,432 DEBUG : BROWSER_OUTPUT: __metrics 07:26:55 INFO - 2016-02-10 07:26:55,593 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] 07:26:56 INFO - 2016-02-10 07:26:56,837 INFO : Browser initialized. 07:26:56 INFO - 2016-02-10 07:26:56,838 INFO : Running cycle 1/1 for kraken test... 07:26:56 INFO - 2016-02-10 07:26:56,838 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUNfS2q/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:27:08 INFO - 2016-02-10 07:27:08,452 DEBUG : BROWSER_OUTPUT: RSS: Main: 132464640 07:27:08 INFO - 2016-02-10 07:27:08,452 DEBUG : BROWSER_OUTPUT: 07:27:10 INFO - 2016-02-10 07:27:10,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45867/tests/kraken/driver.html?testName=audio-beat-detection) 07:27:11 INFO - 2016-02-10 07:27:11,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 164249600 07:27:11 INFO - 2016-02-10 07:27:11,462 DEBUG : BROWSER_OUTPUT: 07:27:14 INFO - 2016-02-10 07:27:14,031 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45867/tests/kraken/driver.html?testName=audio-dft) 07:27:14 INFO - 2016-02-10 07:27:14,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 162816000 07:27:14 INFO - 2016-02-10 07:27:14,597 DEBUG : BROWSER_OUTPUT: 07:27:17 INFO - 2016-02-10 07:27:17,395 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45867/tests/kraken/driver.html?testName=audio-fft) 07:27:17 INFO - 2016-02-10 07:27:17,963 DEBUG : BROWSER_OUTPUT: RSS: Main: 159567872 07:27:17 INFO - 2016-02-10 07:27:17,963 DEBUG : BROWSER_OUTPUT: 07:27:19 INFO - 2016-02-10 07:27:19,942 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45867/tests/kraken/driver.html?testName=audio-oscillator) 07:27:20 INFO - 2016-02-10 07:27:20,508 DEBUG : BROWSER_OUTPUT: RSS: Main: 158789632 07:27:20 INFO - 2016-02-10 07:27:20,509 DEBUG : BROWSER_OUTPUT: 07:27:22 INFO - 2016-02-10 07:27:22,671 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45867/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:27:23 INFO - 2016-02-10 07:27:23,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 158474240 07:27:23 INFO - 2016-02-10 07:27:23,224 DEBUG : BROWSER_OUTPUT: 07:27:26 INFO - 2016-02-10 07:27:26,107 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45867/tests/kraken/driver.html?testName=imaging-darkroom) 07:27:26 INFO - 2016-02-10 07:27:26,674 DEBUG : BROWSER_OUTPUT: RSS: Main: 158142464 07:27:26 INFO - 2016-02-10 07:27:26,674 DEBUG : BROWSER_OUTPUT: 07:27:29 INFO - 2016-02-10 07:27:29,995 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45867/tests/kraken/driver.html?testName=imaging-desaturate) 07:27:30 INFO - 2016-02-10 07:27:30,565 DEBUG : BROWSER_OUTPUT: RSS: Main: 158912512 07:27:30 INFO - 2016-02-10 07:27:30,565 DEBUG : BROWSER_OUTPUT: 07:27:33 INFO - 2016-02-10 07:27:33,227 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45867/tests/kraken/driver.html?testName=json-parse-financial) 07:27:33 INFO - 2016-02-10 07:27:33,795 DEBUG : BROWSER_OUTPUT: RSS: Main: 159469568 07:27:33 INFO - 2016-02-10 07:27:33,795 DEBUG : BROWSER_OUTPUT: 07:27:35 INFO - 2016-02-10 07:27:35,325 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45867/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:27:35 INFO - 2016-02-10 07:27:35,887 DEBUG : BROWSER_OUTPUT: RSS: Main: 162914304 07:27:35 INFO - 2016-02-10 07:27:35,887 DEBUG : BROWSER_OUTPUT: 07:27:37 INFO - 2016-02-10 07:27:37,596 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:27:38 INFO - 2016-02-10 07:27:38,182 DEBUG : BROWSER_OUTPUT: RSS: Main: 162324480 07:27:38 INFO - 2016-02-10 07:27:38,182 DEBUG : BROWSER_OUTPUT: 07:27:40 INFO - 2016-02-10 07:27:40,183 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:27:40 INFO - 2016-02-10 07:27:40,753 DEBUG : BROWSER_OUTPUT: RSS: Main: 168275968 07:27:40 INFO - 2016-02-10 07:27:40,753 DEBUG : BROWSER_OUTPUT: 07:27:43 INFO - 2016-02-10 07:27:43,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:27:43 INFO - 2016-02-10 07:27:43,712 DEBUG : BROWSER_OUTPUT: RSS: Main: 173027328 07:27:43 INFO - 2016-02-10 07:27:43,712 DEBUG : BROWSER_OUTPUT: 07:27:46 INFO - 2016-02-10 07:27:46,192 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:27:46 INFO - 2016-02-10 07:27:46,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 170930176 07:27:46 INFO - 2016-02-10 07:27:46,765 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,405 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45867/tests/kraken/driver.html?testName=ai-astar) 07:27:48 INFO - 2016-02-10 07:27:48,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 228802560 07:27:48 INFO - 2016-02-10 07:27:48,657 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,657 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:27:48 INFO - 2016-02-10 07:27:48,657 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:27:48 INFO - 2016-02-10 07:27:48,657 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:27:48 INFO - 2016-02-10 07:27:48,657 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:27:48 INFO - 2016-02-10 07:27:48,658 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;105;112;110;110;100;110;111;111 07:27:48 INFO - 2016-02-10 07:27:48,658 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;123;150;162;121;142;151;130;169;130 07:27:48 INFO - 2016-02-10 07:27:48,658 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;161;160;159;160;162;162;161;160 07:27:48 INFO - 2016-02-10 07:27:48,658 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;72;154;71;72;70;72;159;69 07:27:48 INFO - 2016-02-10 07:27:48,658 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;135;91;92;92;132;91;92;133 07:27:48 INFO - 2016-02-10 07:27:48,659 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;109;108;109;110;109;109 07:27:48 INFO - 2016-02-10 07:27:48,659 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;146;145;146;145;146;146;146 07:27:48 INFO - 2016-02-10 07:27:48,659 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;95;96;95;96;96;96 07:27:48 INFO - 2016-02-10 07:27:48,659 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;56;56;57;55;56;59;57;53;54 07:27:48 INFO - 2016-02-10 07:27:48,659 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;63;63;64;63;63;63;63;63 07:27:48 INFO - 2016-02-10 07:27:48,660 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;77;78;76;76;78;76;77;77;76 07:27:48 INFO - 2016-02-10 07:27:48,660 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;125;114;114;123;110;119;115;110;117 07:27:48 INFO - 2016-02-10 07:27:48,660 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;145;149;144;136;134;146;143;141;132 07:27:48 INFO - 2016-02-10 07:27:48,660 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;65;64;65;69;65;64;65;66;62 07:27:48 INFO - 2016-02-10 07:27:48,660 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:27:48 INFO - 2016-02-10 07:27:48,661 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:27:48 INFO - 2016-02-10 07:27:48,661 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,876 07:27:48 INFO - 2016-02-10 07:27:48,661 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:27:48 INFO - 2016-02-10 07:27:48,661 DEBUG : BROWSER_OUTPUT: __startTimestamp1455118068655__endTimestamp 07:27:48 INFO - 2016-02-10 07:27:48,661 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,663 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:27:48 INFO - 2016-02-10 07:27:48,663 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:27:48 INFO - 2016-02-10 07:27:48,663 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,664 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.90 Median:110.50 stddev:3.67 (3.3%) stddev-sans-first:3.89 07:27:48 INFO - 2016-02-10 07:27:48,664 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 105.0 112.0 110.0 110.0 100.0 110.0 111.0 111.0 07:27:48 INFO - 2016-02-10 07:27:48,664 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,664 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.30 Median:150.50 stddev:16.72 (11.1%) stddev-sans-first:17.19 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: Values: 155.0 123.0 150.0 162.0 121.0 142.0 151.0 130.0 169.0 130.0 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.07 (0.7%) stddev-sans-first:1.01 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 161.0 160.0 159.0 160.0 162.0 162.0 161.0 160.0 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.10 Median:71.50 stddev:36.08 (50.5%) stddev-sans-first:37.67 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 72.0 154.0 71.0 72.0 70.0 72.0 159.0 69.0 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.20 Median:92.00 stddev:20.12 (21.9%) stddev-sans-first:20.85 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 135.0 91.0 92.0 92.0 132.0 91.0 92.0 133.0 07:27:48 INFO - 2016-02-10 07:27:48,665 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 109.0 108.0 109.0 110.0 109.0 109.0 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 95.0 96.0 95.0 96.0 96.0 96.0 07:27:48 INFO - 2016-02-10 07:27:48,667 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.77 (3.2%) stddev-sans-first:1.76 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: Values: 54.0 56.0 56.0 57.0 55.0 56.0 59.0 57.0 53.0 54.0 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 63.0 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.50 Median:77.00 stddev:2.42 (3.1%) stddev-sans-first:0.83 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: Values: 84.0 77.0 78.0 76.0 76.0 78.0 76.0 77.0 77.0 76.0 07:27:48 INFO - 2016-02-10 07:27:48,668 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.10 Median:116.00 stddev:5.00 (4.3%) stddev-sans-first:5.24 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: Values: 114.0 125.0 114.0 114.0 123.0 110.0 119.0 115.0 110.0 117.0 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.50 Median:143.50 stddev:5.84 (4.1%) stddev-sans-first:5.84 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: Values: 135.0 145.0 149.0 144.0 136.0 134.0 146.0 143.0 141.0 132.0 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.79 (2.8%) stddev-sans-first:1.87 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 65.0 69.0 65.0 64.0 65.0 66.0 62.0 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:27:48 INFO - 2016-02-10 07:27:48,669 DEBUG : BROWSER_OUTPUT: 07:27:49 INFO - 2016-02-10 07:27:49,297 INFO : Browser exited with error code: 0 07:27:49 INFO - 2016-02-10 07:27:49,353 INFO : Completed test kraken (00:00:58) 07:27:49 INFO - 2016-02-10 07:27:49,353 INFO : Starting test v8_7 07:27:49 INFO - 2016-02-10 07:27:49,353 DEBUG : operating with platform_type : linux_ 07:27:49 INFO - 2016-02-10 07:27:49,353 INFO : Initialising browser for v8_7 test... 07:27:49 INFO - 2016-02-10 07:27:49,360 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyvYAEQ/profile http://localhost:45867/getInfo.html 07:27:53 INFO - 2016-02-10 07:27:53,699 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:27:53 INFO - 2016-02-10 07:27:53,699 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:27:53 INFO - 2016-02-10 07:27:53,699 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:27:53 INFO - 2016-02-10 07:27:53,699 DEBUG : BROWSER_OUTPUT: __metrics 07:27:53 INFO - 2016-02-10 07:27:53,921 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] 07:27:55 INFO - 2016-02-10 07:27:55,209 INFO : Browser initialized. 07:27:55 INFO - 2016-02-10 07:27:55,209 INFO : Running cycle 1/1 for v8_7 test... 07:27:55 INFO - 2016-02-10 07:27:55,210 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyvYAEQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:28:06 INFO - 2016-02-10 07:28:06,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 134451200 07:28:06 INFO - 2016-02-10 07:28:06,879 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,274 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45867/tests/v8_7/run.html (next: http://localhost:45867/tests/v8_7/run.html) 07:28:28 INFO - 2016-02-10 07:28:28,524 DEBUG : BROWSER_OUTPUT: RSS: Main: 420896768 07:28:28 INFO - 2016-02-10 07:28:28,525 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,525 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:28:28 INFO - 2016-02-10 07:28:28,525 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:28:28 INFO - 2016-02-10 07:28:28,525 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:28:28 INFO - 2016-02-10 07:28:28,525 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:28:28 INFO - 2016-02-10 07:28:28,525 DEBUG : BROWSER_OUTPUT: |0;Richards;168.20857863751053 07:28:28 INFO - 2016-02-10 07:28:28,525 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.93485073396008 07:28:28 INFO - 2016-02-10 07:28:28,526 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.85495971103086 07:28:28 INFO - 2016-02-10 07:28:28,526 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5175.257731958763 07:28:28 INFO - 2016-02-10 07:28:28,526 DEBUG : BROWSER_OUTPUT: |4;RayTrace;952.3809523809524 07:28:28 INFO - 2016-02-10 07:28:28,526 DEBUG : BROWSER_OUTPUT: |5;Earley;541.4185165132648 07:28:28 INFO - 2016-02-10 07:28:28,526 DEBUG : BROWSER_OUTPUT: |6;Boyer;10578.947368421053 07:28:28 INFO - 2016-02-10 07:28:28,526 DEBUG : BROWSER_OUTPUT: |7;RegExp;24071.428571428572 07:28:28 INFO - 2016-02-10 07:28:28,527 DEBUG : BROWSER_OUTPUT: |8;Splay;533.8624338624338 07:28:28 INFO - 2016-02-10 07:28:28,527 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 07:28:28 INFO - 2016-02-10 07:28:28,527 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:28:28 INFO - 2016-02-10 07:28:28,527 DEBUG : BROWSER_OUTPUT: __startTimestamp1455118108523__endTimestamp 07:28:28 INFO - 2016-02-10 07:28:28,527 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,528 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:28:28 INFO - 2016-02-10 07:28:28,528 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:28:28 INFO - 2016-02-10 07:28:28,528 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,528 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.21 Median:168.21 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,528 DEBUG : BROWSER_OUTPUT: Values: 168.2 07:28:28 INFO - 2016-02-10 07:28:28,529 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,529 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.93 Median:164.93 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,529 DEBUG : BROWSER_OUTPUT: Values: 164.9 07:28:28 INFO - 2016-02-10 07:28:28,529 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,529 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.85 Median:277.85 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,529 DEBUG : BROWSER_OUTPUT: Values: 277.9 07:28:28 INFO - 2016-02-10 07:28:28,530 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,530 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,530 DEBUG : BROWSER_OUTPUT: Values: 5175.3 07:28:28 INFO - 2016-02-10 07:28:28,530 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,530 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:952.38 Median:952.38 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,530 DEBUG : BROWSER_OUTPUT: Values: 952.4 07:28:28 INFO - 2016-02-10 07:28:28,530 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,531 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:541.42 Median:541.42 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,531 DEBUG : BROWSER_OUTPUT: Values: 541.4 07:28:28 INFO - 2016-02-10 07:28:28,531 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,531 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10578.95 Median:10578.95 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,531 DEBUG : BROWSER_OUTPUT: Values: 10578.9 07:28:28 INFO - 2016-02-10 07:28:28,531 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24071.43 Median:24071.43 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: Values: 24071.4 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:533.86 Median:533.86 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: Values: 533.9 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: Values: 4822.1 07:28:28 INFO - 2016-02-10 07:28:28,532 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:28:28 INFO - 2016-02-10 07:28:28,533 DEBUG : BROWSER_OUTPUT: 07:28:29 INFO - 2016-02-10 07:28:29,193 INFO : Browser exited with error code: 0 07:28:29 INFO - 2016-02-10 07:28:29,205 INFO : Completed test v8_7 (00:00:39) 07:28:29 INFO - 2016-02-10 07:28:29,503 INFO : Completed test suite (00:20:09) 07:28:29 INFO - 2016-02-10 07:28:29,503 DEBUG : Working with test: dromaeo_css 07:28:29 INFO - 2016-02-10 07:28:29,503 DEBUG : Generating results file: dromaeo_css 07:28:29 INFO - 2016-02-10 07:28:29,505 DEBUG : Working with test: kraken 07:28:29 INFO - 2016-02-10 07:28:29,505 DEBUG : Generating results file: kraken 07:28:29 INFO - 2016-02-10 07:28:29,505 INFO : javascript benchmark 07:28:29 INFO - 2016-02-10 07:28:29,506 DEBUG : Working with test: v8_7 07:28:29 INFO - 2016-02-10 07:28:29,506 DEBUG : Generating results file: v8_7 07:28:29 INFO - 2016-02-10 07:28:29,507 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:28:29 INFO - 2016-02-10 07:28:29,570 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 07:28:29 INFO - 2016-02-10 07:28:29,571 DEBUG : process_Request line: dromaeo_css 7153.73 graph.html#tests=[[72,53,35]] 07:28:29 INFO - 2016-02-10 07:28:29,571 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:28:29 INFO - 2016-02-10 07:28:29,620 DEBUG : process_Request line: kraken 1473.30 graph.html#tests=[[232,53,35]] 07:28:29 INFO - 2016-02-10 07:28:29,620 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:28:29 INFO - 2016-02-10 07:28:29,660 DEBUG : process_Request line: v8_7 23249.22 graph.html#tests=[[230,53,35]] 07:28:29 INFO - 2016-02-10 07:28:29,661 INFO : javascript benchmark 07:28:29 INFO - 2016-02-10 07:28:29,665 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3264.6572109928456, 3314.370269648765, 3324.1024923270716, 3326.7671263011584, 3345.514900339451, 5418.810341638038, 5448.046591846431, 5457.536109430698, 5468.425815289397, 5479.123340265553, 5454.427285135063, 5455.5908306877045, 5472.753726082326, 5492.395836457997, 5501.807436739705, 4875.804967801284, 4950.430700469438, 4988.551030407262, 5057.0, 5069.44236134025, 4889.33993910821, 5039.546440820325, 5058.822941197059, 5075.314832497603, 5131.666441681297, 4973.552380285773, 5039.546440820325, 5051.722155281458, 5056.8482945511505, 5081.923771143417, 5047.545720885119, 5081.441041485434, 5106.66806657566, 5110.66780659256, 5126.051680439094, 4830.830920917786, 4833.879153021167, 4934.506549345073, 5007.198848184273, 5008.12357837377, 5234.8691282717855, 5250.44870288618, 5265.894682106336, 5270.5520030797425, 5293.285406470116, 5880.26496687914, 5911.261092363455, 5929.436703513159, 5930.55520835939, 5934.495567876736, 5771.942280577225, 5842.766289348463, 5844.824655260349, 5863.355030946593, 5876.0010798163985, 5537.031019571559, 5595.804146854841, 5600.187972743929, 5607.719614019324, 5615.326160860641, 6081.513478921675, 6085.57400981927, 6092.116643086728, 6095.451409373178, 6100.481459076028, 5631.239782629293, 5677.545796336283, 5727.8568035799435, 5798.565107616945, 5823.475887170091, 5883.264591926009, 5956.910646340309, 5958.2850057992455, 5967.850803729854, 5968.522518198533, 5721.771129154788, 5880.411958804086, 5922.585419020627, 5927.170196172539, 5934.080217566284, 5863.736131874076, 5902.144189092557, 5990.550708696778, 6002.039673652194, 6038.275406951107, 4546.909061818745, 4553.886152846139, 4577.633789296849, 4583.74789386587, 4617.515160908123, 3925.941110883339, 4065.2072845794787, 4115.773632450184, 4127.9587204128175, 4165.354370072643, 5426.0, 5481.150421684645, 5481.342238931354, 5501.229827824108, 5538.6399884007415, 5932.0, 5971.28344598651, 5977.282726072899, 5990.28116625999, 5992.310884248367, 5365.3293338332705, 5410.215518749759, 5461.371942226615, 5582.748776304995, 5590.217369568264, 5086.287919691246, 5124.743762811919, 5166.560842328444, 5173.5343819055715, 5180.481951804864, 6103.908441373383, 6112.908306375408, 6121.693915304217, 6127.142200091991, 6134.38656134383, 5752.712364381848, 5786.855328616818, 5877.529797616266, 5944.227250457413, 5966.522678185822, 6043.607165534227, 6045.667488288011, 6047.0627052806885, 6051.243594550681, 6093.086037094471, 5764.798232061858, 5766.682832444256, 5789.681567513659, 5798.043322851682, 5809.157672137432, 5641.323041235078, 5733.340665823317, 5756.481916627356, 5918.704064796657, 5922.289325280994, 5654.151877218285, 5664.207011018543, 5689.374168841341, 5695.800646977336, 5695.943040569541, 5653.491185793298, 5665.66006039639, 5675.574331925205, 5677.659340439587, 5689.061304884648, 5574.637648552831, 5578.470045345685, 5581.525570326406, 5584.888302234014, 5659.320881494247, 5731.312242530923, 5767.365589785204, 5786.016377215799, 5787.131930210502, 5830.5627077968475, 5661.773529058792, 5677.7161141941915, 5680.0, 5682.6306290091015, 5684.8010319638615], "unit": "ms", "name": "dojo.html", "value": 5426.192334010327}, {"lowerIsBetter": false, "replicates": [551.114932869511, 588.661519626215, 593.2406519654842, 631.3560168627997, 642.1042645509511, 33257.0, 41485.79257103714, 42403.0, 42438.78780606093, 42470.362944555854, 46731.53268467314, 47081.29378059332, 47135.528644713544, 47247.52752472474, 47265.52734472654, 8175.1416101309105, 8296.419250652456, 9092.045335239787, 9196.034416386283, 9270.119338662817, 10683.78632427351, 10990.395528245943, 11011.944940275287, 11027.062699670538, 11029.44852757363, 10712.625058122967, 10811.945940270287, 10825.350478971248, 10826.566937322497, 10829.29609575379, 8429.32565394767, 8674.522901240429, 10464.476776161162, 10663.413512256822, 10753.892461075446, 10457.633982810565, 10615.628453004185, 10637.57449702011, 10646.787064258671, 10653.627123050734, 43433.782831085955, 43807.561924380985, 43808.0, 43893.341599876025, 44051.77974110141, 3625.474306225609, 3653.2328211075705, 3699.8890033299044, 4123.0, 4311.059690449827, 32695.0, 32971.340573188405, 33269.0, 33321.33357332864, 33394.1651458713, 4310.0, 4312.5903039211225, 4382.298832186835, 4396.120775844812, 4426.62373698236, 301398.0, 302376.0, 302706.0, 303774.4811275952, 304367.0, 63984.0, 64526.677366612865, 64551.35448645453, 64568.677156613914, 64578.677106615105, 67572.662136689, 67692.3230767696, 67737.0, 67786.0, 67821.66089169522, 15656.37374505007, 15973.440929567641, 16056.27746751399, 16077.196140192944, 16205.0, 12832.358382080858, 12863.614091577268, 12867.42096605655, 12876.098673092794, 12920.0, 20286.898565507374, 20596.27913023037, 20602.484937876674, 20657.690134647994, 20658.58682826365, 7731.729389471341, 7786.18245084258, 7809.258120478659, 7863.803404914808, 7907.683692652229, 1010.8433192855119, 1106.397013627575, 1108.772701596171, 1123.7977164110375, 1138.8291756236465, 30241.848790755907, 30418.84790576033, 30718.38563228723, 32834.17914552155, 33221.236727045594, 1057.513543769859, 1124.8481455003634, 1125.5497800879714, 1203.6990752311922, 1276.113101394522, 13946.442142314396, 14133.929330353283, 14313.28433578317, 15901.125438101015, 16093.839061609468, 337588.0, 346464.0, 346638.0, 346683.0, 346986.0, 55584.0, 55754.0, 56007.43992560104, 56126.43873561294, 56199.0, 1449.1812126148693, 1459.4016453254328, 1460.664047269134, 1468.9118652880861, 1498.4380857178558, 29874.103776886728, 30140.24649383783, 30747.231319217197, 31043.68956310408, 31176.53235201519, 49138.26292605685, 49489.75255123701, 49594.752026239636, 49609.50390496049, 49632.75183624203, 318272.0, 321253.0, 321402.0, 321582.0, 322194.0, 30312.09063728092, 30836.22909427282, 34034.489482658275, 34322.313553729284, 34393.0, 42079.158416832106, 43285.78357108194, 43408.782956085015, 44404.11191776211, 44411.11177776491, 36363.0, 36611.0, 37203.441948371335, 37555.81222093872, 37995.43006854955, 37087.25825483421, 37218.62781372152, 37885.81057094697, 37982.81008594939, 38144.61855381411, 36273.45589816208, 36980.81509592435, 37278.627213727515, 37289.81355093207, 37415.0, 32985.83507082545, 33641.0, 34355.65644343525, 35049.123771905906, 36870.8156459216, 26301.0, 26681.7331826687, 26820.32949176208, 26842.731572684024, 26895.549373436057], "unit": "ms", "name": "ext.html", "value": 20047.091359998274}, {"lowerIsBetter": false, "replicates": [354.68610279902293, 360.117711606564, 360.52410817720613, 361.49751844935537, 366.1012215012145, 15379.38482460703, 15819.762703559456, 17265.827341726577, 17813.732794008083, 18421.078946052694, 18455.723164152514, 18546.814531854678, 18761.718574221362, 18939.33712320069, 18994.335198268065, 5540.944590554093, 5685.862827434509, 5687.146927960818, 5690.7723691052515, 5693.829185124432, 4272.722273052257, 4395.142947125312, 5640.210370548127, 6060.424259695322, 6208.627482351073, 6054.409275684596, 6071.301800292957, 6111.358307377728, 6136.263648362203, 6140.570160088796, 6095.756169753205, 6115.266168059839, 6126.387361263838, 6133.570650054498, 6146.078088286748, 5237.266782650432, 5261.710605916673, 5266.104762190437, 5283.841484755463, 5304.92042619361, 22151.33545993623, 22240.0, 22384.88807555968, 22641.320760377213, 22743.431414214778, 2361.6103342948395, 2373.4541055557147, 2377.322463098026, 2382.845115067515, 2388.522295540899, 13911.373988170557, 14033.5088271911, 14203.289835508287, 14265.928670356685, 14501.202433866134, 1851.7407562941198, 1916.4154932745507, 1934.4486821256016, 1937.94186174415, 2004.8271761019676, 61526.69236653833, 64315.67842160759, 64494.355056449305, 64769.67615161941, 64889.3511064888, 9420.764480887896, 9740.0, 10012.69961901144, 10025.699229023141, 10049.095581397709, 13010.089293749535, 13013.739725205443, 13020.674483137997, 13046.0, 13060.151090179108, 7332.450066245053, 7370.852582948311, 7536.5101268417375, 7618.771436856903, 7622.466427350032, 6983.266756990544, 7005.92994070063, 7036.240939765059, 7036.753713619999, 7037.894431583531, 6098.0852872069545, 6128.417800308964, 6164.506839452833, 6199.132121502993, 6233.472799164234, 1581.7073841339388, 1666.4417420164139, 1671.1059583123215, 1674.0625249860118, 1682.6466442047308, 458.53458739379556, 1014.7310962594918, 1085.625459216569, 1172.7068232941765, 1185.6739396665987, 10597.894021059845, 10933.125349971982, 10974.780504389868, 11072.889271107348, 11101.0, 451.1563376485927, 453.5441880909665, 456.1629410032561, 456.8058575105567, 472.1925507382346, 5893.821235752866, 6035.366286539938, 6068.302145253264, 6074.787382441594, 6079.483243924227, 401000.0, 632527.0, 638199.0, 646851.0, 650387.0, 43885.341719874225, 56113.438865611635, 70470.29529704637, 77507.61246193733, 77537.22462775413, 464.55170760216555, 469.7275580163497, 470.5177193376816, 471.12257653251766, 471.5284715284715, 10220.488975551341, 10397.064264216257, 10486.37081775096, 10495.632652857113, 10514.474276286308, 35796.463053054744, 35982.82008590045, 36437.63562364342, 37627.81186094052, 37849.0, 47040.529594703614, 49604.25593616171, 49918.0, 51804.740976294874, 53197.734011329696, 9232.861507077265, 9559.091886270793, 9665.420074795535, 9880.45657488845, 9983.800323993624, 13513.391897364445, 17803.19885605125, 18676.34632787846, 18971.051447427297, 19159.233630654617, 15376.923115384352, 15423.305951232445, 15497.14765687898, 15567.922160389126, 15650.060996340257, 2934.1784300395925, 14472.335681396353, 14710.852891471377, 14807.703845922806, 15362.231888405311, 15255.389784408499, 15855.920720396323, 15910.284037218122, 16118.516444506684, 16237.269322880262, 9613.567389467351, 9614.663486777928, 9694.321397502106, 9730.805383892424, 9741.853872191781, 11527.365994870363, 15848.762268566212, 15993.360265589246, 16012.679746404774, 16209.513714588582], "unit": "ms", "name": "jquery.html", "value": 8545.768148255793}, {"lowerIsBetter": false, "replicates": [1539.5766164304807, 1731.48055583325, 1801.7657704498413, 1815.4644379907925, 1839.9264029438823, 7287.963560182198, 7573.394128469723, 7573.6970521179155, 7584.4690871639, 7584.507007044538, 6787.287334829846, 6967.094277743886, 7289.890651640217, 7475.2151024142495, 7477.1775104738435, 2107.9999999999964, 2124.8193903518218, 2141.175647375756, 2141.550274442361, 2147.967780483294, 2012.0543344628074, 2253.43664083979, 2278.054607337953, 2308.0652335804025, 2311.4914718761847, 2019.2730616977876, 2201.2625770366867, 2256.3005468304796, 2257.3340864445026, 2261.027758064032, 2162.4485756132244, 2188.0153930731235, 2193.8683678979314, 2196.5936301784222, 2198.7801219877965, 2273.4202778291597, 2281.190177486985, 2306.8269879759087, 2308.4459729664927, 2309.399556115422, 7488.4009279257525, 7537.811554711121, 7549.50928189666, 7555.055618047744, 7559.58422286774, 4554.795034223468, 4610.031893302411, 4619.653525985531, 4622.098690755304, 4623.884402889921, 2939.955900661492, 2960.985195074032, 3008.533677280025, 3011.6837732038257, 3023.727864492206, 926.9304802139799, 938.2634631813993, 977.154761131616, 993.8409854423257, 994.9005099490065, 10414.687559373308, 10430.530626121767, 10433.634822781167, 10438.843417348746, 10450.268481206318, 3875.0312421894528, 3906.277338692351, 3909.081365879016, 3922.4116382542848, 3966.2662407454714, 9006.144416280444, 9012.729618111467, 9014.323925705598, 9014.819703606023, 9022.413543119676, 4482.8206871725415, 4495.393121928563, 4503.279475284005, 4520.81916723334, 4526.705764125321, 4129.855455059059, 4135.697255364551, 4137.565555616678, 4138.668906487474, 4139.81370838313, 3550.928981420409, 3584.605693373727, 3613.3495970725, 3613.9277214455565, 3623.4564815277918, 1027.9023492768174, 1028.341861208827, 1071.8499410082595, 1079.8164312066967, 1080.983785243222, 1108.2463924531235, 1128.5824245029391, 1163.0521125282867, 1168.1705988748083, 1204.2112416367293, 7389.7413590524075, 7428.1457632371885, 7496.437767167376, 7520.247975202436, 7530.6611202496015, 1174.6123779152858, 1192.5289510643247, 1197.0423661071281, 1200.765850659113, 1220.7192345760525, 5881.705914704247, 5939.910901336483, 5939.910901336483, 5940.227770389822, 5943.10853371989, 8579.914200858037, 9049.09509049103, 9135.0, 9144.954275228582, 9181.58682859273, 9073.183413492818, 9077.818443631091, 9080.046595107551, 9087.409318394282, 9117.680881169128, 6620.933790662128, 6874.106366172366, 6946.7221311148, 6963.54736942087, 7078.929210707827, 7299.197088320387, 7472.925270747223, 7479.813004674709, 7486.663100160615, 7488.438367122379, 7221.819454513679, 7358.080239970004, 7474.215207403145, 7527.435442341955, 7548.924510754822, 8353.832923341377, 8415.0, 8418.158184181533, 8430.6206220719, 8439.873401898854, 6734.4612431004425, 6924.93075069263, 6928.307169283233, 6949.3745562899585, 6973.19808222061, 7451.105867295742, 7495.475316727995, 7498.0, 7508.211637777955, 7511.661975211238, 5973.970130149321, 6772.932270677427, 6965.199002114619, 7059.611721355374, 7184.353408193288, 6820.215675197217, 6993.055937448379, 7029.578225306498, 7034.648267586498, 7129.607871567114, 6920.134983127109, 7071.398931090709, 7077.5753454792875, 7079.929200708133, 7081.079459670416, 6366.840828979312, 6391.840203994937, 6392.744290228339, 6394.616323020634, 6398.680065996775, 6249.906251406142, 6275.560710750204, 6338.11266422692, 6447.3552644735155, 6649.867002660017], "unit": "ms", "name": "mootools.html", "value": 4402.227514430069}, {"lowerIsBetter": false, "replicates": [344.5090745687395, 353.344545867416, 353.5050928699821, 355.04669961154286, 357.7638758419442, 5982.491488223501, 6053.061775424816, 6058.878822423549, 6069.302030266521, 6071.058985857188, 5716.7141642917895, 5806.9709651451685, 5892.587518873681, 5989.580729348947, 5999.730012149453, 4612.607928326096, 4633.0, 4640.280756482751, 4645.651576131787, 4651.488336283014, 4411.713238639494, 4646.186917289478, 4653.0, 4653.092646933849, 4658.301254811771, 4468.8882777930485, 4469.776511174445, 4585.326355880096, 4598.287265473857, 4602.792874320664, 4569.611583015431, 4594.632429405656, 4606.9078618427775, 4610.65420093491, 4623.514530974233, 4068.674506039539, 4071.918561628751, 4073.8370465181356, 4074.6129117733767, 4074.877753667365, 5561.916571251434, 5666.886662266732, 5668.0, 5670.914936275959, 5676.2620859288445, 5311.548518375943, 5327.094393952999, 5329.706866122362, 5329.813456529003, 5332.893342133174, 6373.776917807854, 6380.840478988062, 6392.968035159841, 6397.232332120128, 6399.872002559922, 4407.493138289105, 4418.624416924566, 4421.182081314967, 4421.889452763675, 4435.844745433927, 6501.5773974691965, 6553.541252112354, 6614.834629134262, 6616.8089743846085, 6620.404163625297, 4506.481754598198, 4507.143642707875, 4511.571400716926, 4518.051209246029, 4518.141553104965, 4557.248054071108, 4557.840475583405, 4559.6124329431705, 4569.954300456953, 4573.519780423074, 5271.525562699376, 5288.233206185082, 5289.2859463972645, 5294.8941021180135, 5299.4435584263865, 6292.8426789329715, 6397.6801159941815, 6408.455281301049, 6409.935900641027, 6410.391012853772, 1590.8886377953431, 1609.9678006439806, 1610.645657955248, 1611.9119594273818, 1614.1122382689473, 1061.1881910338598, 1071.796358691846, 1074.3553867679486, 1077.3482043363651, 1092.759592889563, 6082.9695851520455, 6121.418465245794, 6122.846928826726, 6128.142060111588, 6128.601640893327, 6391.169148010729, 6597.208334999831, 6599.274079851309, 6605.5376123670885, 6614.040964060185, 6059.666718330446, 6082.422169893853, 6179.876402472015, 6189.102580125857, 6195.442410183015, 3529.8941031768572, 3581.462780582933, 3584.874529391459, 3586.0676224181902, 3588.4796704478226, 6642.800715978528, 6860.0, 6862.759803406857, 6864.5881247125335, 6872.209695885037, 6761.8647627047185, 6792.592444453348, 6798.490113241527, 6803.65981700913, 6809.557378770364, 6840.657967101625, 6844.589324640538, 6857.27998560154, 6860.279670634612, 6862.4853136015, 6427.710753016032, 6633.170853643294, 6637.435817955335, 6642.368974947372, 6646.0, 6598.274189839018, 6605.471562275103, 6613.503987200883, 6622.0, 6622.0, 6871.14110736158, 6876.0, 6876.346747059021, 6880.105586273752, 6890.517663763489, 4473.373727678063, 4500.819967201275, 4503.189425903238, 4506.053728717006, 4508.549145085465, 6521.250056243593, 6711.664416779044, 6720.462363011045, 6721.0, 6722.731090756511, 6482.837929051623, 6511.120998665119, 6514.348565143448, 6533.67331633407, 6545.050967609765, 6310.7791227306825, 6453.741850326122, 6496.285408605144, 6509.0, 6509.772157974448, 6335.334789847, 6336.873262534816, 6347.5556711029785, 6347.8095657128515, 6348.460380867678, 6459.257185423737, 6466.256380516113, 6468.320826313169, 6543.345665433419, 6544.214694236532, 6245.594036387621, 6362.1729175208775, 6367.490600751836, 6376.39424254695, 6377.1390862233], "unit": "ms", "name": "prototype.html", "value": 4832.52460194539}, {"lowerIsBetter": false, "replicates": [927.92112670423, 938.648006997376, 938.7043081429354, 939.4363381970818, 955.8052434456929, 14145.929270353647, 14417.351219195136, 14425.278736063206, 14432.350544225508, 14436.927815360908, 13608.455661773542, 13647.522336718217, 13685.247311397869, 13739.793903091437, 13745.312734363268, 769.9653515591812, 775.9961200193991, 776.0919723922996, 895.4850960697609, 915.7115508614799, 888.7022847346146, 905.3481493324796, 905.3888625178012, 905.3888625178012, 905.4522014181424, 951.1154626197633, 958.4967891856761, 959.2374062620199, 960.1646567486262, 960.6781728121053, 810.5055915891331, 815.2214635023538, 940.83535381308, 948.7438391634255, 951.9666811661593, 711.8042538302009, 717.426059152681, 718.0234880562431, 718.105958082191, 719.2663483247111, 13703.657408564766, 13779.655508612264, 13837.861621383758, 13919.164850108924, 13959.441622335095, 12243.632691019284, 12946.805797913039, 13042.739145217138, 13465.192088474723, 13517.053806233567, 10666.3066900652, 10730.30253033558, 10853.565857365775, 10956.397398143099, 11182.105431565455, 3906.140649057203, 3928.489296391451, 3930.921381572381, 3957.861474848395, 3985.880423587297, 46007.539924600664, 46318.5368146321, 46481.76759116183, 46893.76553117247, 46982.06035879297, 10887.782244355069, 11010.39442830636, 11052.834207486894, 11086.057685096857, 11221.66335009951, 16393.508194754177, 16477.423290184786, 16508.092054936853, 16516.17419129039, 16747.581310467336, 7862.803429914184, 7905.960470197612, 8003.239692229229, 8407.411481196257, 8483.575821208915, 4667.766611669403, 4682.2508398656055, 4698.060387922429, 4753.04939012199, 4762.9285560715925, 3172.0959526534825, 3195.888143914952, 3197.4884018557386, 3202.5676533668125, 3205.3589282143666, 579.2325169150807, 592.2655906675691, 597.6175247841385, 600.6756351570191, 605.3946053946054, 650.4276236911583, 731.4075598765018, 735.4667865797254, 753.284379839155, 771.1517330936038, 9293.256539476826, 9431.104045115702, 9462.621495140118, 9547.713568592953, 9614.855777163348, 599.2120361724319, 650.5413683353224, 652.4714980865514, 676.9936320906866, 758.518340853562, 4316.784160792011, 4387.4954380246045, 4480.283154695233, 4519.638428925678, 4537.636989040869, 56759.0, 57541.0, 57847.421525785045, 58113.0, 58315.708421457624, 52024.0, 57000.0, 57092.143617845766, 57735.71132144312, 58253.70873145607, 46499.0, 47438.0, 47681.0, 47882.0, 48145.0, 9049.864252036356, 9297.30270229722, 9656.27577931672, 9845.45849978258, 10056.547455364673, 36024.459633106046, 37044.814775925945, 37582.62417375791, 37754.0, 39109.0, 43691.78154109336, 44067.55932440634, 44136.55863441453, 44914.77542612266, 45624.0, 11674.241174323643, 11893.167478276784, 12427.565035223724, 12462.189957652723, 12827.935860320638, 12901.161424507378, 13611.659708507366, 13644.386002629706, 13831.585052448443, 14592.197429141499, 11617.825732614187, 12206.389680515762, 12323.815142772686, 12692.746145076862, 13847.23840188759, 12280.75438491243, 12286.569970051005, 12701.301428421526, 12875.163114397534, 12884.420201091161, 12441.264257276285, 12909.160904541175, 12939.288339141438, 12971.740565188833, 13374.665633359244, 10264.0, 10652.307600005975, 10751.19366047534, 10811.405372704263, 11079.169062320198, 10533.525991330256, 10826.621068262888, 10842.241043126905, 10889.183311251847, 10974.286671366335], "unit": "ms", "name": "yui.html", "value": 6776.5669024152}], "name": "dromaeo_css", "value": 7153.728751186418}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 105.0, 112.0, 110.0, 110.0, 100.0, 110.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.9}, {"lowerIsBetter": true, "replicates": [155.0, 123.0, 150.0, 162.0, 121.0, 142.0, 151.0, 130.0, 169.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.3}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 161.0, 160.0, 159.0, 160.0, 162.0, 162.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 72.0, 154.0, 71.0, 72.0, 70.0, 72.0, 159.0, 69.0], "unit": "ms", "name": "audio-fft", "value": 88.1}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 135.0, 91.0, 92.0, 92.0, 132.0, 91.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 104.2}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 108.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [54.0, 56.0, 56.0, 57.0, 55.0, 56.0, 59.0, 57.0, 53.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.7}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.3}, {"lowerIsBetter": true, "replicates": [84.0, 77.0, 78.0, 76.0, 76.0, 78.0, 76.0, 77.0, 77.0, 76.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.5}, {"lowerIsBetter": true, "replicates": [114.0, 125.0, 114.0, 114.0, 123.0, 110.0, 119.0, 115.0, 110.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.1}, {"lowerIsBetter": true, "replicates": [135.0, 145.0, 149.0, 144.0, 136.0, 134.0, 146.0, 143.0, 141.0, 132.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.5}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 65.0, 69.0, 65.0, 64.0, 65.0, 66.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.9}], "name": "kraken", "value": 1473.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.20857863751053], "unit": "score", "name": "Richards", "value": 209.87038999999996}, {"lowerIsBetter": false, "replicates": [164.93485073396008], "unit": "score", "name": "DeltaBlue", "value": 400.87343399999986}, {"lowerIsBetter": false, "replicates": [277.85495971103086], "unit": "score", "name": "Encrypt", "value": 957.9854189999998}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [952.3809523809524], "unit": "score", "name": "RayTrace", "value": 776.9884500000003}, {"lowerIsBetter": false, "replicates": [541.4185165132648], "unit": "score", "name": "Earley", "value": 1230.9571610000005}, {"lowerIsBetter": false, "replicates": [10578.947368421053], "unit": "score", "name": "Boyer", "value": 62.99899004975127}, {"lowerIsBetter": false, "replicates": [24071.428571428572], "unit": "score", "name": "RegExp", "value": 37.845074183976266}, {"lowerIsBetter": false, "replicates": [533.8624338624338], "unit": "score", "name": "Splay", "value": 152.6441922695739}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23249.220984815795}]} 07:28:29 INFO - RETURN: dromaeo_css: 7153.73 07:28:29 INFO - RETURN: kraken: 1473.30 07:28:29 INFO - RETURN: v8_7: 23249.22 07:28:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23249.22"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1473.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7153.73"}}} 07:28:29 INFO - Return code: 0 07:28:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 07:28:29 INFO - # TBPL SUCCESS # 07:28:29 INFO - Running post-action listener: _resource_record_post_action 07:28:29 INFO - Running post-run listener: _resource_record_post_run 07:28:30 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 323584; Write bytes: 347222016; Read time: 296; Write time: 567604 07:28:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 25309184; Read time: 0; Write time: 202696 07:28:30 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 323584; Write bytes: 266059776; Read time: 296; Write time: 211176 07:28:30 INFO - Running post-run listener: _upload_blobber_files 07:28:30 INFO - Blob upload gear active. 07:28:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:28:30 INFO - Copying logs to upload dir... 07:28:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1269.752332 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-02-10 07:28:30.297087) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-10 07:28:30.300844) ========= 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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009961 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107601/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-02-10 07:28:30.353022) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:28:30.353412) ========= 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=1861a34ebfea852630dec091000001fa-1455116839.452635-1160487331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:28:30.403762) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-10 07:28:30.404125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-10 07:28:30.404489) ========= ========= Total master_lag: 0.25 =========