builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1455976571.9 results: success (0) buildid: 20160220031821 builduid: 28c2254ee47647498d79983ddba2a192 revision: 099eb8a467c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.900004) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.900532) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.900859) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006692 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.922997) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.923357) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.949139) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.949449) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004704 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.998734) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.999068) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.999485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:11.999797) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-20 05:56:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.8M=0s 2016-02-20 05:56:12 (43.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105578 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:12.119421) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:12.119772) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022372 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:12.169335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-20 05:56:12.169725) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 099eb8a467c2 --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 099eb8a467c2 --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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-20 05:56:13,078 Setting DEBUG logging. 2016-02-20 05:56:13,078 attempt 1/10 2016-02-20 05:56:13,078 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/099eb8a467c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-20 05:56:14,202 unpacking tar archive at: mozilla-beta-099eb8a467c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.448958 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-20 05:56:13.631967) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:13.632362) ========= echo 099eb8a467c2 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'099eb8a467c2'] 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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 099eb8a467c2 program finished with exit code 0 elapsedTime=0.004646 script_repo_revision: '099eb8a467c2' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:13.683955) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:13.684303) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-20 05:56:13.693898) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-20 05:56:13.694236) ========= /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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:56:14 INFO - MultiFileLogger online at 20160220 05:56:14 in /builds/slave/test-pgo 05:56:14 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 05:56:14 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:56:14 INFO - {'append_to_log': False, 05:56:14 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:56:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:56:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:56:14 INFO - 'branch': 'Mozilla-Beta', 05:56:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:56:14 INFO - 'config_files': ('talos/linux_config.py',), 05:56:14 INFO - 'default_actions': ('clobber', 05:56:14 INFO - 'read-buildbot-config', 05:56:14 INFO - 'download-and-extract', 05:56:14 INFO - 'populate-webroot', 05:56:14 INFO - 'create-virtualenv', 05:56:14 INFO - 'install', 05:56:14 INFO - 'run-tests'), 05:56:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:56:14 INFO - 'download_minidump_stackwalk': True, 05:56:14 INFO - 'download_symbols': 'ondemand', 05:56:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:56:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:56:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:56:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:56:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:56:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:56:14 INFO - 'installer_path': 'installer.exe', 05:56:14 INFO - 'log_level': 'info', 05:56:14 INFO - 'log_name': 'talos', 05:56:14 INFO - 'log_to_console': True, 05:56:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:56:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:56:14 INFO - 'opt_config_files': (), 05:56:14 INFO - 'pip_index': False, 05:56:14 INFO - 'sps_profile': False, 05:56:14 INFO - 'sps_profile_interval': 0, 05:56:14 INFO - 'suite': 'dromaeojs', 05:56:14 INFO - 'system_bits': '32', 05:56:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:56:14 INFO - 'title': 'talos-linux64-ix-014', 05:56:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:56:14 INFO - 'use_talos_json': True, 05:56:14 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:56:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:56:14 INFO - 'work_dir': 'build'} 05:56:14 INFO - ##### 05:56:14 INFO - ##### Running clobber step. 05:56:14 INFO - ##### 05:56:14 INFO - Running pre-action listener: _resource_record_pre_action 05:56:14 INFO - Running main action method: clobber 05:56:14 INFO - rmtree: /builds/slave/test-pgo/build 05:56:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:56:15 INFO - Running post-action listener: _resource_record_post_action 05:56:15 INFO - ##### 05:56:15 INFO - ##### Running read-buildbot-config step. 05:56:15 INFO - ##### 05:56:15 INFO - Running pre-action listener: _resource_record_pre_action 05:56:15 INFO - Running main action method: read_buildbot_config 05:56:15 INFO - Using buildbot properties: 05:56:15 INFO - { 05:56:15 INFO - "properties": { 05:56:15 INFO - "buildnumber": 130, 05:56:15 INFO - "product": "firefox", 05:56:15 INFO - "script_repo_revision": "production", 05:56:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:56:15 INFO - "repository": "", 05:56:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:56:15 INFO - "buildid": "20160220031821", 05:56:15 INFO - "slavename": "talos-linux64-ix-014", 05:56:15 INFO - "pgo_build": "True", 05:56:15 INFO - "basedir": "/builds/slave/test-pgo", 05:56:15 INFO - "project": "", 05:56:15 INFO - "platform": "ubuntu64_hw", 05:56:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:56:15 INFO - "slavebuilddir": "test", 05:56:15 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:56:15 INFO - "branch": "mozilla-beta", 05:56:15 INFO - "repo_path": "releases/mozilla-beta", 05:56:15 INFO - "stage_platform": "linux64-pgo", 05:56:15 INFO - "builduid": "28c2254ee47647498d79983ddba2a192", 05:56:15 INFO - "revision": "099eb8a467c2" 05:56:15 INFO - }, 05:56:15 INFO - "sourcestamp": { 05:56:15 INFO - "repository": "", 05:56:15 INFO - "hasPatch": false, 05:56:15 INFO - "project": "", 05:56:15 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 05:56:15 INFO - "changes": [ 05:56:15 INFO - { 05:56:15 INFO - "category": null, 05:56:15 INFO - "files": [ 05:56:15 INFO - { 05:56:15 INFO - "url": null, 05:56:15 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.tar.bz2" 05:56:15 INFO - } 05:56:15 INFO - ], 05:56:15 INFO - "repository": "", 05:56:15 INFO - "rev": "099eb8a467c2", 05:56:15 INFO - "who": "sendchange", 05:56:15 INFO - "when": 1455976533, 05:56:15 INFO - "number": 7631518, 05:56:15 INFO - "comments": "No bug, Automated blocklist update from host bld-linux64-spot-304 - a=blocklist-update", 05:56:15 INFO - "project": "", 05:56:15 INFO - "at": "Sat 20 Feb 2016 05:55:33", 05:56:15 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 05:56:15 INFO - "revlink": "", 05:56:15 INFO - "properties": [ 05:56:15 INFO - [ 05:56:15 INFO - "buildid", 05:56:15 INFO - "20160220031821", 05:56:15 INFO - "Change" 05:56:15 INFO - ], 05:56:15 INFO - [ 05:56:15 INFO - "builduid", 05:56:15 INFO - "28c2254ee47647498d79983ddba2a192", 05:56:15 INFO - "Change" 05:56:15 INFO - ], 05:56:15 INFO - [ 05:56:15 INFO - "pgo_build", 05:56:15 INFO - "True", 05:56:15 INFO - "Change" 05:56:15 INFO - ] 05:56:15 INFO - ], 05:56:15 INFO - "revision": "099eb8a467c2" 05:56:15 INFO - } 05:56:15 INFO - ], 05:56:15 INFO - "revision": "099eb8a467c2" 05:56:15 INFO - } 05:56:15 INFO - } 05:56:15 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.tar.bz2. 05:56:15 INFO - Running post-action listener: _resource_record_post_action 05:56:15 INFO - ##### 05:56:15 INFO - ##### Running download-and-extract step. 05:56:15 INFO - ##### 05:56:15 INFO - Running pre-action listener: _resource_record_pre_action 05:56:15 INFO - Running main action method: download_and_extract 05:56:15 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/test_packages.json 05:56:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:56:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:56:15 INFO - Downloaded 1270 bytes. 05:56:15 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:56:15 INFO - Using the following test package requirements: 05:56:15 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 05:56:15 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:56:15 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:56:15 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:56:15 INFO - u'jsshell-linux-x86_64.zip'], 05:56:15 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:56:15 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:56:15 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 05:56:15 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:56:15 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 05:56:15 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:56:15 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 05:56:15 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:56:15 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:56:15 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 05:56:15 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:56:15 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:56:15 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:56:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:16 INFO - Downloaded 22255786 bytes. 05:56:16 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 05:56:16 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:56:16 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 05:56:17 INFO - Return code: 0 05:56:17 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 05:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:56:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:17 INFO - Downloaded 22255786 bytes. 05:56:17 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 05:56:17 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:56:17 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 05:56:19 INFO - Return code: 0 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 05:56:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:19 INFO - Downloaded 11132038 bytes. 05:56:19 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 05:56:19 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 05:56:19 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 05:56:19 INFO - Return code: 0 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.tar.bz2 05:56:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/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 05:56:20 INFO - Downloaded 58331067 bytes. 05:56:20 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.tar.bz2 05:56:20 INFO - mkdir: /builds/slave/test-pgo/properties 05:56:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:56:20 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:56:20 INFO - Contents: 05:56:20 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.tar.bz2 05:56:20 INFO - Running post-action listener: _resource_record_post_action 05:56:20 INFO - Running post-action listener: set_extra_try_arguments 05:56:20 INFO - ##### 05:56:20 INFO - ##### Running populate-webroot step. 05:56:20 INFO - ##### 05:56:20 INFO - Running pre-action listener: _resource_record_pre_action 05:56:20 INFO - Running main action method: populate_webroot 05:56:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:56:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:56:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:56:20 INFO - '--tppagecycles', 05:56:20 INFO - '7'], 05:56:20 INFO - 'tests': ['tsvgm']}}, 05:56:20 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:56:20 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:56:20 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:56:20 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:56:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:56:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'tests': ['damp', 'tps']}, 05:56:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['damp', 'tps']}, 05:56:20 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:56:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:56:20 INFO - 'other': {'tests': ['a11yr', 05:56:20 INFO - 'ts_paint', 05:56:20 INFO - 'tpaint', 05:56:20 INFO - 'sessionrestore', 05:56:20 INFO - 'sessionrestore_no_auto_restore']}, 05:56:20 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['a11yr', 05:56:20 INFO - 'ts_paint', 05:56:20 INFO - 'tpaint', 05:56:20 INFO - 'sessionrestore', 05:56:20 INFO - 'sessionrestore_no_auto_restore']}, 05:56:20 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['a11yr', 05:56:20 INFO - 'ts_paint', 05:56:20 INFO - 'tpaint', 05:56:20 INFO - 'sessionrestore', 05:56:20 INFO - 'sessionrestore_no_auto_restore']}, 05:56:20 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['a11yr', 05:56:20 INFO - 'ts_paint', 05:56:20 INFO - 'tpaint', 05:56:20 INFO - 'sessionrestore', 05:56:20 INFO - 'sessionrestore_no_auto_restore']}, 05:56:20 INFO - 'other_l64': {'tests': ['a11yr', 05:56:20 INFO - 'ts_paint', 05:56:20 INFO - 'tpaint', 05:56:20 INFO - 'sessionrestore', 05:56:20 INFO - 'sessionrestore_no_auto_restore']}, 05:56:20 INFO - 'other_nol64': {'tests': ['a11yr', 05:56:20 INFO - 'ts_paint', 05:56:20 INFO - 'tpaint', 05:56:20 INFO - 'sessionrestore', 05:56:20 INFO - 'sessionrestore_no_auto_restore']}, 05:56:20 INFO - 'svgr': {'tests': ['tsvgx', 05:56:20 INFO - 'tsvgr_opacity', 05:56:20 INFO - 'tart', 05:56:20 INFO - 'tscrollx', 05:56:20 INFO - 'cart']}, 05:56:20 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['tsvgx', 05:56:20 INFO - 'tsvgr_opacity', 05:56:20 INFO - 'tart', 05:56:20 INFO - 'tscrollx', 05:56:20 INFO - 'cart']}, 05:56:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'tests': ['tp5o']}, 05:56:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'talos_options': ['--e10s'], 05:56:20 INFO - 'tests': ['tp5o']}, 05:56:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'talos_options': ['--xperf_path', 05:56:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:20 INFO - 'tests': ['tp5n']}, 05:56:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:56:20 INFO - 'talos_options': ['--e10s', 05:56:20 INFO - '--xperf_path', 05:56:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:56:20 INFO - 'tests': ['tp5n']}}, 05:56:20 INFO - 'talos.zip': {'path': '', 05:56:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:56:20 INFO - Running post-action listener: _resource_record_post_action 05:56:20 INFO - ##### 05:56:20 INFO - ##### Running create-virtualenv step. 05:56:20 INFO - ##### 05:56:20 INFO - Running pre-action listener: _resource_record_pre_action 05:56:20 INFO - Running main action method: create_virtualenv 05:56:20 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:56:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 05:56:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:56:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:56:20 INFO - Using real prefix '/usr' 05:56:20 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:56:21 INFO - Installing distribute.............................................................................................................................................................................................done. 05:56:23 INFO - Installing pip.................done. 05:56:23 INFO - Return code: 0 05:56:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:56: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')]} 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56: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')]} 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:23 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:23 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 05:56:23 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 05:56:23 INFO - Using env: {'DISPLAY': ':0', 05:56:23 INFO - 'HOME': '/home/cltbld', 05:56:23 INFO - 'LANG': 'en_US.UTF-8', 05:56:23 INFO - 'LANGUAGE': 'en_US:en', 05:56:23 INFO - 'LOGNAME': 'cltbld', 05:56:23 INFO - 'MAIL': '/var/mail/cltbld', 05:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:23 INFO - 'MOZ_NO_REMOTE': '1', 05:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:23 INFO - 'NO_EM_RESTART': '1', 05:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:23 INFO - 'SHELL': '/bin/bash', 05:56:23 INFO - 'SHLVL': '1', 05:56:23 INFO - 'TERM': 'linux', 05:56:23 INFO - 'TMOUT': '86400', 05:56:23 INFO - 'USER': 'cltbld', 05:56:23 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:23 INFO - '_': '/tools/buildbot/bin/python'} 05:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:56:23 INFO - Cleaning up... 05:56:23 INFO - Return code: 0 05:56:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:56: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')]} 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56: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')]} 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:23 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:23 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 05:56:23 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 05:56:23 INFO - Using env: {'DISPLAY': ':0', 05:56:23 INFO - 'HOME': '/home/cltbld', 05:56:23 INFO - 'LANG': 'en_US.UTF-8', 05:56:23 INFO - 'LANGUAGE': 'en_US:en', 05:56:23 INFO - 'LOGNAME': 'cltbld', 05:56:23 INFO - 'MAIL': '/var/mail/cltbld', 05:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:23 INFO - 'MOZ_NO_REMOTE': '1', 05:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:23 INFO - 'NO_EM_RESTART': '1', 05:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:23 INFO - 'SHELL': '/bin/bash', 05:56:23 INFO - 'SHLVL': '1', 05:56:23 INFO - 'TERM': 'linux', 05:56:23 INFO - 'TMOUT': '86400', 05:56:23 INFO - 'USER': 'cltbld', 05:56:23 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:23 INFO - '_': '/tools/buildbot/bin/python'} 05:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:23 INFO - Downloading/unpacking psutil>=0.7.1 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:56:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:56:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:56:25 INFO - Installing collected packages: psutil 05:56:25 INFO - Running setup.py install for psutil 05:56:25 INFO - building 'psutil._psutil_linux' extension 05:56:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:56:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:56:25 INFO - building 'psutil._psutil_posix' extension 05:56:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:56:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:56:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:56:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:56:26 INFO - Successfully installed psutil 05:56:26 INFO - Cleaning up... 05:56:26 INFO - Return code: 0 05:56:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:26 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:26 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 05:56:26 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 05:56:26 INFO - Using env: {'DISPLAY': ':0', 05:56:26 INFO - 'HOME': '/home/cltbld', 05:56:26 INFO - 'LANG': 'en_US.UTF-8', 05:56:26 INFO - 'LANGUAGE': 'en_US:en', 05:56:26 INFO - 'LOGNAME': 'cltbld', 05:56:26 INFO - 'MAIL': '/var/mail/cltbld', 05:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:26 INFO - 'MOZ_NO_REMOTE': '1', 05:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:26 INFO - 'NO_EM_RESTART': '1', 05:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:26 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:26 INFO - 'SHELL': '/bin/bash', 05:56:26 INFO - 'SHLVL': '1', 05:56:26 INFO - 'TERM': 'linux', 05:56:26 INFO - 'TMOUT': '86400', 05:56:26 INFO - 'USER': 'cltbld', 05:56:26 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:26 INFO - '_': '/tools/buildbot/bin/python'} 05:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:56:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:56:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:56:28 INFO - Installing collected packages: mozsystemmonitor 05:56:28 INFO - Running setup.py install for mozsystemmonitor 05:56:28 INFO - Successfully installed mozsystemmonitor 05:56:28 INFO - Cleaning up... 05:56:28 INFO - Return code: 0 05:56:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:28 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:28 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 05:56:28 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 05:56:28 INFO - Using env: {'DISPLAY': ':0', 05:56:28 INFO - 'HOME': '/home/cltbld', 05:56:28 INFO - 'LANG': 'en_US.UTF-8', 05:56:28 INFO - 'LANGUAGE': 'en_US:en', 05:56:28 INFO - 'LOGNAME': 'cltbld', 05:56:28 INFO - 'MAIL': '/var/mail/cltbld', 05:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:28 INFO - 'MOZ_NO_REMOTE': '1', 05:56:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:28 INFO - 'NO_EM_RESTART': '1', 05:56:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:28 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:28 INFO - 'SHELL': '/bin/bash', 05:56:28 INFO - 'SHLVL': '1', 05:56:28 INFO - 'TERM': 'linux', 05:56:28 INFO - 'TMOUT': '86400', 05:56:28 INFO - 'USER': 'cltbld', 05:56:28 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:28 INFO - '_': '/tools/buildbot/bin/python'} 05:56:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:56:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:56:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:56:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:31 INFO - Downloading docopt-0.6.1.tar.gz 05:56:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:56:31 INFO - Installing collected packages: blobuploader, requests, docopt 05:56:31 INFO - Running setup.py install for blobuploader 05:56:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:56:31 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:56:31 INFO - Running setup.py install for requests 05:56:31 INFO - Running setup.py install for docopt 05:56:31 INFO - Successfully installed blobuploader requests docopt 05:56:31 INFO - Cleaning up... 05:56:31 INFO - Return code: 0 05:56:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:31 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:31 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 05:56:31 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 05:56:31 INFO - Using env: {'DISPLAY': ':0', 05:56:31 INFO - 'HOME': '/home/cltbld', 05:56:31 INFO - 'LANG': 'en_US.UTF-8', 05:56:31 INFO - 'LANGUAGE': 'en_US:en', 05:56:31 INFO - 'LOGNAME': 'cltbld', 05:56:31 INFO - 'MAIL': '/var/mail/cltbld', 05:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:31 INFO - 'MOZ_NO_REMOTE': '1', 05:56:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:31 INFO - 'NO_EM_RESTART': '1', 05:56:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:31 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:31 INFO - 'SHELL': '/bin/bash', 05:56:31 INFO - 'SHLVL': '1', 05:56:31 INFO - 'TERM': 'linux', 05:56:31 INFO - 'TMOUT': '86400', 05:56:31 INFO - 'USER': 'cltbld', 05:56:31 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:31 INFO - '_': '/tools/buildbot/bin/python'} 05:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:56:31 INFO - Running setup.py (path:/tmp/pip-FPPF6I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:56:32 INFO - Running setup.py (path:/tmp/pip-ShlSD0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:56:32 INFO - Running setup.py (path:/tmp/pip-O8OO8A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:56:32 INFO - Running setup.py (path:/tmp/pip-atwO1u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:56:32 INFO - Running setup.py (path:/tmp/pip-VKuAGj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:56:32 INFO - Running setup.py (path:/tmp/pip-TkW7u5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:56:32 INFO - Running setup.py (path:/tmp/pip-aPKNQN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:56:32 INFO - Running setup.py (path:/tmp/pip-5HRDBI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:56:32 INFO - Running setup.py (path:/tmp/pip-N2I1_C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:56:32 INFO - Running setup.py (path:/tmp/pip-gu9mZ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:56:32 INFO - Running setup.py (path:/tmp/pip-gWy4hJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:56:32 INFO - Running setup.py (path:/tmp/pip-BKe8XJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:56:32 INFO - Running setup.py (path:/tmp/pip-gdVbKc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:56:32 INFO - Running setup.py (path:/tmp/pip-zYU8MN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:56:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:56:32 INFO - Running setup.py (path:/tmp/pip-Erudm2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:56:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:56:33 INFO - Running setup.py (path:/tmp/pip-HS4wj_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:56:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:56:33 INFO - Running setup.py (path:/tmp/pip-UcYw0C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:56:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:56:33 INFO - Running setup.py install for manifestparser 05:56:33 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Running setup.py install for mozcrash 05:56:33 INFO - Running setup.py install for mozdebug 05:56:33 INFO - Running setup.py install for mozdevice 05:56:33 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Running setup.py install for mozfile 05:56:33 INFO - Running setup.py install for mozhttpd 05:56:33 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Running setup.py install for mozinfo 05:56:33 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Running setup.py install for mozInstall 05:56:33 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:56:33 INFO - Running setup.py install for mozleak 05:56:34 INFO - Running setup.py install for mozlog 05:56:34 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:56:34 INFO - Running setup.py install for moznetwork 05:56:34 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:56:34 INFO - Running setup.py install for mozprocess 05:56:34 INFO - Running setup.py install for mozprofile 05:56:34 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:56:34 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:56:34 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:56:34 INFO - Running setup.py install for mozrunner 05:56:34 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:56:34 INFO - Running setup.py install for mozscreenshot 05:56:34 INFO - Running setup.py install for moztest 05:56:34 INFO - Running setup.py install for mozversion 05:56:34 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:56:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:56:34 INFO - Cleaning up... 05:56:34 INFO - Return code: 0 05:56:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:34 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:34 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 05:56:34 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 05:56:34 INFO - Using env: {'DISPLAY': ':0', 05:56:34 INFO - 'HOME': '/home/cltbld', 05:56:34 INFO - 'LANG': 'en_US.UTF-8', 05:56:34 INFO - 'LANGUAGE': 'en_US:en', 05:56:34 INFO - 'LOGNAME': 'cltbld', 05:56:34 INFO - 'MAIL': '/var/mail/cltbld', 05:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:34 INFO - 'MOZ_NO_REMOTE': '1', 05:56:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:34 INFO - 'NO_EM_RESTART': '1', 05:56:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:34 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:34 INFO - 'SHELL': '/bin/bash', 05:56:34 INFO - 'SHLVL': '1', 05:56:34 INFO - 'TERM': 'linux', 05:56:34 INFO - 'TMOUT': '86400', 05:56:34 INFO - 'USER': 'cltbld', 05:56:34 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:34 INFO - '_': '/tools/buildbot/bin/python'} 05:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:56:35 INFO - Running setup.py (path:/tmp/pip-NQHOz5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:56:35 INFO - Running setup.py (path:/tmp/pip-U0HMjj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:56:35 INFO - Running setup.py (path:/tmp/pip-TIxRMx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:56:35 INFO - Running setup.py (path:/tmp/pip-gf1Gg4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:56:35 INFO - Running setup.py (path:/tmp/pip-SjpOp9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:56:35 INFO - Running setup.py (path:/tmp/pip-YsFgoY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:56:35 INFO - Running setup.py (path:/tmp/pip-dUtwzW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:56:35 INFO - Running setup.py (path:/tmp/pip-lYNngA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:56:35 INFO - Running setup.py (path:/tmp/pip-GHXsgu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:56:35 INFO - Running setup.py (path:/tmp/pip-zvGdzc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:56:35 INFO - Running setup.py (path:/tmp/pip-FeDqRu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:56:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:56:35 INFO - Running setup.py (path:/tmp/pip-qCTcz4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:56:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:56:36 INFO - Running setup.py (path:/tmp/pip-1YBP0n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:56:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:56:36 INFO - Running setup.py (path:/tmp/pip-GyYbL4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 05:56:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:56:36 INFO - Running setup.py (path:/tmp/pip-cebpw4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 05:56:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:56:36 INFO - Running setup.py (path:/tmp/pip-llak52-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 05:56:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:56:36 INFO - Running setup.py (path:/tmp/pip-BA20JU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:38 INFO - Downloading blessings-1.6.tar.gz 05:56:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:56:38 INFO - Installing collected packages: blessings 05:56:38 INFO - Running setup.py install for blessings 05:56:38 INFO - Successfully installed blessings 05:56:38 INFO - Cleaning up... 05:56:38 INFO - Return code: 0 05:56:38 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:56:38 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:56:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:56:38 INFO - Reading from file tmpfile_stdout 05:56:38 INFO - Current package versions: 05:56:38 INFO - argparse == 1.2.1 05:56:38 INFO - blessings == 1.6 05:56:38 INFO - blobuploader == 1.2.4 05:56:38 INFO - docopt == 0.6.1 05:56:38 INFO - manifestparser == 1.1 05:56:38 INFO - mozInstall == 1.12 05:56:38 INFO - mozcrash == 0.16 05:56:38 INFO - mozdebug == 0.1 05:56:38 INFO - mozdevice == 0.47 05:56:38 INFO - mozfile == 1.2 05:56:38 INFO - mozhttpd == 0.7 05:56:38 INFO - mozinfo == 0.9 05:56:38 INFO - mozleak == 0.1 05:56:38 INFO - mozlog == 3.1 05:56:38 INFO - moznetwork == 0.27 05:56:38 INFO - mozprocess == 0.22 05:56:38 INFO - mozprofile == 0.27 05:56:38 INFO - mozrunner == 6.11 05:56:38 INFO - mozscreenshot == 0.1 05:56:38 INFO - mozsystemmonitor == 0.0 05:56:38 INFO - moztest == 0.7 05:56:38 INFO - mozversion == 1.4 05:56:38 INFO - psutil == 3.1.1 05:56:38 INFO - requests == 1.2.3 05:56:38 INFO - wsgiref == 0.1.2 05:56:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:38 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:38 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 05:56:38 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 05:56:38 INFO - Using env: {'DISPLAY': ':0', 05:56:38 INFO - 'HOME': '/home/cltbld', 05:56:38 INFO - 'LANG': 'en_US.UTF-8', 05:56:38 INFO - 'LANGUAGE': 'en_US:en', 05:56:38 INFO - 'LOGNAME': 'cltbld', 05:56:38 INFO - 'MAIL': '/var/mail/cltbld', 05:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:38 INFO - 'MOZ_NO_REMOTE': '1', 05:56:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:38 INFO - 'NO_EM_RESTART': '1', 05:56:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:38 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:38 INFO - 'SHELL': '/bin/bash', 05:56:38 INFO - 'SHLVL': '1', 05:56:38 INFO - 'TERM': 'linux', 05:56:38 INFO - 'TMOUT': '86400', 05:56:38 INFO - 'USER': 'cltbld', 05:56:38 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:38 INFO - '_': '/tools/buildbot/bin/python'} 05:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:56:38 INFO - Cleaning up... 05:56:38 INFO - Return code: 0 05:56:38 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:38 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:38 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 05:56:38 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 05:56:38 INFO - Using env: {'DISPLAY': ':0', 05:56:38 INFO - 'HOME': '/home/cltbld', 05:56:38 INFO - 'LANG': 'en_US.UTF-8', 05:56:38 INFO - 'LANGUAGE': 'en_US:en', 05:56:38 INFO - 'LOGNAME': 'cltbld', 05:56:38 INFO - 'MAIL': '/var/mail/cltbld', 05:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:38 INFO - 'MOZ_NO_REMOTE': '1', 05:56:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:38 INFO - 'NO_EM_RESTART': '1', 05:56:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:38 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:38 INFO - 'SHELL': '/bin/bash', 05:56:38 INFO - 'SHLVL': '1', 05:56:38 INFO - 'TERM': 'linux', 05:56:38 INFO - 'TMOUT': '86400', 05:56:38 INFO - 'USER': 'cltbld', 05:56:38 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:38 INFO - '_': '/tools/buildbot/bin/python'} 05:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:39 INFO - Downloading/unpacking jsonschema 05:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:56:40 INFO - Downloading/unpacking functools32 (from jsonschema) 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56:41 INFO - Downloading functools32-3.2.3-2.tar.gz 05:56:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:56:41 INFO - warning: no files found matching '*.txt' 05:56:41 INFO - no previously-included directories found matching 'build' 05:56:41 INFO - no previously-included directories found matching 'dist' 05:56:41 INFO - no previously-included directories found matching '.git*' 05:56:41 INFO - Installing collected packages: jsonschema, functools32 05:56:41 INFO - Running setup.py install for functools32 05:56:41 INFO - warning: no files found matching '*.txt' 05:56:41 INFO - no previously-included directories found matching 'build' 05:56:41 INFO - no previously-included directories found matching 'dist' 05:56:41 INFO - no previously-included directories found matching '.git*' 05:56:41 INFO - Successfully installed jsonschema functools32 05:56:41 INFO - Cleaning up... 05:56:41 INFO - Return code: 0 05:56:41 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:41 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 0x7f5a9a233f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd06600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:56:41 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 05:56:41 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 05:56:41 INFO - Using env: {'DISPLAY': ':0', 05:56:41 INFO - 'HOME': '/home/cltbld', 05:56:41 INFO - 'LANG': 'en_US.UTF-8', 05:56:41 INFO - 'LANGUAGE': 'en_US:en', 05:56:41 INFO - 'LOGNAME': 'cltbld', 05:56:41 INFO - 'MAIL': '/var/mail/cltbld', 05:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:41 INFO - 'MOZ_NO_REMOTE': '1', 05:56:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:56:41 INFO - 'NO_EM_RESTART': '1', 05:56:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:56:41 INFO - 'PWD': '/builds/slave/test-pgo', 05:56:41 INFO - 'SHELL': '/bin/bash', 05:56:41 INFO - 'SHLVL': '1', 05:56:41 INFO - 'TERM': 'linux', 05:56:41 INFO - 'TMOUT': '86400', 05:56:41 INFO - 'USER': 'cltbld', 05:56:41 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:41 INFO - '_': '/tools/buildbot/bin/python'} 05:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:41 INFO - Downloading/unpacking flake8 05:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:56:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:56:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:56:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:56:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:56:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:56:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:56:44 INFO - no previously-included directories found matching 'docs/_build' 05:56:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:56:44 INFO - Running setup.py install for pep8 05:56:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:56:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:56:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:56:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:56:44 INFO - no previously-included directories found matching 'docs/_build' 05:56:44 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:56:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:56:44 INFO - Cleaning up... 05:56:44 INFO - Return code: 0 05:56:44 INFO - Running post-action listener: _resource_record_post_action 05:56:44 INFO - Running post-action listener: _start_resource_monitoring 05:56:44 INFO - Starting resource monitoring. 05:56:44 INFO - ##### 05:56:44 INFO - ##### Running install step. 05:56:44 INFO - ##### 05:56:44 INFO - Running pre-action listener: _resource_record_pre_action 05:56:44 INFO - Running main action method: install 05:56:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:56:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:56:44 INFO - Reading from file tmpfile_stdout 05:56:44 INFO - Detecting whether we're running mozinstall >=1.0... 05:56:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:56:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:56:44 INFO - Reading from file tmpfile_stdout 05:56:44 INFO - Output received: 05:56:44 INFO - Usage: mozinstall [options] installer 05:56:44 INFO - Options: 05:56:44 INFO - -h, --help show this help message and exit 05:56:44 INFO - -d DEST, --destination=DEST 05:56:44 INFO - Directory to install application into. [default: 05:56:44 INFO - "/builds/slave/test-pgo"] 05:56:44 INFO - --app=APP Application being installed. [default: firefox] 05:56:44 INFO - mkdir: /builds/slave/test-pgo/build/application 05:56:44 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'] 05:56:44 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 05:56:59 INFO - Reading from file tmpfile_stdout 05:56:59 INFO - Output received: 05:56:59 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:56:59 INFO - Running post-action listener: _resource_record_post_action 05:56:59 INFO - ##### 05:56:59 INFO - ##### Running run-tests step. 05:56:59 INFO - ##### 05:56:59 INFO - Running pre-action listener: _resource_record_pre_action 05:56:59 INFO - Running main action method: run_tests 05:56:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:56:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:56:59 INFO - Python 2.7.3 05:56:59 INFO - Return code: 0 05:56:59 INFO - grabbing minidump binary from tooltool 05:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:59 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 0xd95350>, '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 0xd96250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd966e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:56:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:56:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:56:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:56:59 INFO - Return code: 0 05:56:59 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:56:59 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:56:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:56:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:56:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:56:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:56:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:57:00 INFO - Return code: 0 05:57:00 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/1455967101/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:57:00 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/1455967101/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost 05:57:00 INFO - Using env: {'DISPLAY': ':0', 05:57:00 INFO - 'HOME': '/home/cltbld', 05:57:00 INFO - 'LANG': 'en_US.UTF-8', 05:57:00 INFO - 'LANGUAGE': 'en_US:en', 05:57:00 INFO - 'LOGNAME': 'cltbld', 05:57:00 INFO - 'MAIL': '/var/mail/cltbld', 05:57:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:57:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:00 INFO - 'MOZ_NO_REMOTE': '1', 05:57:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:57:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:00 INFO - 'NO_EM_RESTART': '1', 05:57:00 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:57:00 INFO - 'PWD': '/builds/slave/test-pgo', 05:57:00 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:57:00 INFO - 'SHELL': '/bin/bash', 05:57:00 INFO - 'SHLVL': '1', 05:57:00 INFO - 'TERM': 'linux', 05:57:00 INFO - 'TMOUT': '86400', 05:57:00 INFO - 'USER': 'cltbld', 05:57:00 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864', 05:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:00 INFO - '_': '/tools/buildbot/bin/python'} 05:57:00 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/1455967101/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] with output_timeout 3600 05:57:00 INFO - mozversion INFO | application_buildid: 20160220031821 05:57:00 INFO - mozversion INFO | application_changeset: 099eb8a467c2a8754bd50979f24a6c21a56a7ba1 05:57:00 INFO - mozversion INFO | application_display_name: Nightly 05:57:00 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:57:00 INFO - mozversion INFO | application_name: Firefox 05:57:00 INFO - mozversion INFO | application_remotingname: firefox 05:57:00 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:57:00 INFO - mozversion INFO | application_vendor: Mozilla 05:57:00 INFO - mozversion INFO | application_version: 45.0 05:57:00 INFO - mozversion INFO | platform_buildid: 20160220031821 05:57:00 INFO - mozversion INFO | platform_changeset: 099eb8a467c2a8754bd50979f24a6c21a56a7ba1 05:57:00 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:57:00 INFO - mozversion INFO | platform_version: 45.0 05:57:00 INFO - 2016-02-20 05:57:00,580 DEBUG : using testdate: 1455976620 05:57:00 INFO - 2016-02-20 05:57:00,580 DEBUG : actual date: 1455976620 05:57:00 INFO - 2016-02-20 05:57:00,672 INFO : starting webserver on 'localhost:41711' 05:57:00 INFO - 2016-02-20 05:57:00,673 INFO : Starting test suite talos-linux64-ix-014 05:57:00 INFO - 2016-02-20 05:57:00,673 INFO : Starting test dromaeo_css 05:57:00 INFO - 2016-02-20 05:57:00,674 DEBUG : operating with platform_type : linux_ 05:57:00 INFO - 2016-02-20 05:57:00,674 INFO : Initialising browser for dromaeo_css test... 05:57:00 INFO - 2016-02-20 05:57:00,690 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFbTc2V/profile http://localhost:41711/getInfo.html 05:57:04 INFO - 2016-02-20 05:57:04,404 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:57:04 INFO - 2016-02-20 05:57:04,404 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:57:04 INFO - 2016-02-20 05:57:04,404 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:57:04 INFO - 2016-02-20 05:57:04,404 DEBUG : BROWSER_OUTPUT: __metrics 05:57:04 INFO - 2016-02-20 05:57:04,561 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:05 INFO - 2016-02-20 05:57:05,341 INFO : Browser initialized. 05:57:05 INFO - 2016-02-20 05:57:05,342 INFO : Running cycle 1/1 for dromaeo_css test... 05:57:05 INFO - 2016-02-20 05:57:05,342 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFbTc2V/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:57:17 INFO - 2016-02-20 05:57:17,011 DEBUG : BROWSER_OUTPUT: RSS: Main: 133636096 05:57:17 INFO - 2016-02-20 05:57:17,012 DEBUG : BROWSER_OUTPUT: 05:58:07 INFO - 2016-02-20 05:58:07,779 DEBUG : BROWSER_OUTPUT: 1455976687772 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] 06:00:15 INFO - 2016-02-20 06:00:15,861 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41711/tests/dromaeo/cssquery-ext.html) 06:00:16 INFO - 2016-02-20 06:00:16,966 DEBUG : BROWSER_OUTPUT: RSS: Main: 212103168 06:00:16 INFO - 2016-02-20 06:00:16,966 DEBUG : BROWSER_OUTPUT: 06:03:19 INFO - 2016-02-20 06:03:19,037 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/dromaeo/cssquery-ext.html (next: http://localhost:41711/tests/dromaeo/cssquery-jquery.html) 06:03:19 INFO - 2016-02-20 06:03:19,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 211382272 06:03:19 INFO - 2016-02-20 06:03:19,600 DEBUG : BROWSER_OUTPUT: 06:06:21 INFO - 2016-02-20 06:06:21,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41711/tests/dromaeo/cssquery-mootools.html) 06:06:21 INFO - 2016-02-20 06:06:21,847 DEBUG : BROWSER_OUTPUT: RSS: Main: 214843392 06:06:21 INFO - 2016-02-20 06:06:21,848 DEBUG : BROWSER_OUTPUT: 06:09:23 INFO - 2016-02-20 06:09:23,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41711/tests/dromaeo/cssquery-prototype.html) 06:09:24 INFO - 2016-02-20 06:09:24,087 DEBUG : BROWSER_OUTPUT: RSS: Main: 196050944 06:09:24 INFO - 2016-02-20 06:09:24,087 DEBUG : BROWSER_OUTPUT: 06:12:26 INFO - 2016-02-20 06:12:26,478 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41711/tests/dromaeo/cssquery-yui.html) 06:12:27 INFO - 2016-02-20 06:12:27,045 DEBUG : BROWSER_OUTPUT: RSS: Main: 198615040 06:12:27 INFO - 2016-02-20 06:12:27,045 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,187 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/dromaeo/cssquery-yui.html (next: http://localhost:41711/tests/dromaeo/cssquery-dojo.html) 06:15:29 INFO - 2016-02-20 06:15:29,437 DEBUG : BROWSER_OUTPUT: RSS: Main: 241315840 06:15:29 INFO - 2016-02-20 06:15:29,437 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,439 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:15:29 INFO - 2016-02-20 06:15:29,439 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:15:29 INFO - 2016-02-20 06:15:29,439 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:15:29 INFO - 2016-02-20 06:15:29,439 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:15:29 INFO - 2016-02-20 06:15:29,443 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3247.0096620530735;3293.505974103886;3319;3319.850606722697;3341.0812026692656;5439.809606663767;5494.478024587663;5496.560275177986;5511.338639363272;5521.613487055908;5453.072977593809;5469.234307196995;5481.671099734009;5497.917531237024;5508.64193827401;4901.1177987962155;4983.700977941335;5010.198368261079;5032.094223039851;5039.168537191359;5049.520295571915;5114.846554603349;5114.923276150861;5115.258287548303;5117.795288188468;5002.699838009731;5054.241863720435;5093.617978651598;5117.078925793356;5120.667156634807;5062.215356619729;5147.38231412231;5151.8196863109615;5152.458991805881;5158.871028224287;4973.2291494818;5075.390953085654;5094.490550944913;5115.2071428928575;5166.302549155854;5254.737263136866;5319.308489896327;5345.01117293302;5395.622306438551;5442.074847275972;5891.322497912752;5987.670678112702;6037.396260373971;6049.818505444844;6059.939400605982;5977.731002104916;6037.335893051804;6069.757209711606;6075.301340345828;6077.696115194267;5392.379876314235;5481.835544933659;5573.41479144692;5642.069058605366;5680.573956953203;6069.817905462754;6070.6357618543025;6073.058675905242;6077;6096.420840020191;5703.9429605704245;5907.734151963172;5910.615809972338;5910.911336329958;5919.763209471659;5948.315943666447;5980.581359304807;6064;6066.45401913821;6083.726232319556;5977.790777322773;6007.699615019232;6019.367966363468;6021.337652858182;6025.517958563304;5746;5791.710414479259;6015.789447369321;6025.517958563304;6027.065804800263;4523.547645235451;4527.660425468037;4546.272596384562;4596.908061838744;4618.930716039262;4043.251998380309;4051.513818341818;4068.6745060395688;4076.164386300801;4083.0404854859084;5465.289512363447;5614.915776263278;5618.353889302701;5629.521490673339;5635.436456354331;5877.23595932526;5901.380355062743;5949.672767997715;5955.076963070731;5985.132155837379;5611.298587676541;5727.198192253088;5748.31020277561;5754.367019627837;5755;5025.648204625714;5121.308623335876;5259.05337039329;5286.20706893969;5287.735613219324;6023.759049638053;6130.134249640431;6156.322804491502;6163.137160797492;6166.167567378437;5977;5979.103134529855;5984.730687119177;6003.699815009145;6037.486813620892;5841.94158058431;5963.552733544915;6076.939230607637;6085.786997455068;6129.693515324305;6015.4586087252355;6017.308009578779;6017.398260173947;6024.668643224665;6031.60794548353;6094.11635312886;6123.387661233841;6125.326214116354;6125.877482450415;6126.111713801563;5662.66024038559;5763.279590051244;5767.826965191051;5874.265716785402;5932.673702946379;5802.854928626818;5830.650160990354;5832.066869300891;5834.7082645866685;5836;5719.28508936383;5721.027425337619;5730.713464326684;5730.828075157752;5731;5588.273524441796;5624.915626265692;5695.601307908407;5804.419558044162;5839;5746.626469279484;5785.739641716049;5797.072468405035;5798.855028624318;5799.739011744566 06:15:29 INFO - 2016-02-20 06:15:29,443 DEBUG : BROWSER_OUTPUT: |1;ext.html;582.4350380131272;613.634887242091;630.5680608782984;634.5716641267146;638.2086213095763;30341;41094;41353.172936541254;41357.99999999996;41381.793091034575;46009;46738;46812.53187468124;46945.765271173594;46978.76510617442;7910.208979102073;8043.839123217532;9027.684031058914;9179.219766319837;9183.265338772882;10856.891431085667;10972.177086718488;11035;11079.72300692485;11088.057515111224;10745;10812.783744325108;10849.077828384558;10863.728406789814;10865.239433239676;10743.677689669283;10805.621803236849;10848.18638602108;10855.51150198243;10858.457077146151;10668.359898406121;10783.676489705322;10796.892031079746;10805.081568066724;10811.405372704497;42579;42693;42928.57071429277;42947.57052429467;42966.78516607428;3692.5753538342897;3756.849726010956;3918.2751191029465;3980.5820388859042;4133.441985331971;30712.84643576768;30976.225594360098;31126.533102003486;31159.532607010686;31807;4000.119973605802;4163.3130533462245;4248.87253382399;4273.888788914874;4311.61195492404;301950;302204;302302.9999999978;302737;302787;59826.40173598295;59958.70020649869;64839.35160648333;65235;72508.63745681237;65686.34313656802;65715;65759.34240657532;66012.33987660157;66027;17021.06384148859;17109.657806843792;17230.224639891236;17245.22396492136;17248.223829927683;13748.518801841887;13781;13813.585592432242;13837.584872453843;13839.723205536035;21524.246651367128;21786.455338616663;21900.561988760135;21987.560248794933;21999.780002200092;8109.270165685117;8271.751847444586;8343.541105239148;8458.28104611115;8572.399932004824;1087.9075278601408;1098.1708809848512;1099.9505022274018;1120.6974116988367;1251.393074358935;28318.85840570784;28625.713742862306;28863.855680721463;29262.707372926423;29289.853550732536;1077.7682798198268;1093.8413929980268;1150.0914277720508;1190.922590031645;1271.1038717703996;14809.777853332209;16919.23863426149;17755.73366399505;17766.111694415227;17902.10489475547;429155;431992;434065;434544;436548;60218.096728549106;60501.39498604958;60685;60737;60802.39197608056;1265.7595056939335;1311.1936159262175;1379.9862001380059;1400.5588239704462;1514.9091054536764;29416.852915735286;29913.401731965674;33190.66809331876;34716;35373;53651.46348536621;54777;54797.72601136969;54850.72574637101;54895.72552137214;320593;321703;324502;325704;325888;43761.34357984569;44392.334114988946;44609;44965.550344496136;45159.32261016153;47383;47946.5205347942;48085;48195.75902120467;49366.50633493619;40709.1858162841;41215.79392103021;41223.793881030404;42458.5754142467;42546.361804572334;40808.15344129419;40818.18363632651;41460.378094329215;43404.13191736211;44144.23423063041;38370.616293836705;38630.4205436913;39149.2170156601;39276.214475709756;39616.60383396129;34272.48591271083;38940.80529597334;39029;39453.802730986165;40321.395179072926;26764.59853102166;28147;28205.15384538467;28578.714212857605;29052 06:15:29 INFO - 2016-02-20 06:15:29,446 DEBUG : BROWSER_OUTPUT: |2;jquery.html;387.50205985308884;387.9185371072075;389.0371330955884;389.3595036165508;391.2995737629643;14656.780148297757;15998.360065597362;17103.74344384835;18377.908110459444;18381.724274135897;17021;17110.743338849927;17334.046627435488;17346.392876249334;17474.126293685298;6175.691215439232;6298.748050077991;6311.715972781236;6313.305536391016;6322.620642761426;4542.9545704543025;4663.114008338422;6243.188385509878;6677.833054173636;6909.689063992108;5262.736863156827;6840.41856442203;6860.725570977155;6889.965550172242;6898.448124150081;5423.83728488146;5426.430224826376;5429.864253393657;5436.456354364572;5445.455454454563;5228.581713462914;5260.526552610283;5362.4637536246455;5364.946350536484;5365.03429382711;13332.400041998133;13434.395452204673;21541.461463463384;21569.352919412442;21687.67468487974;2318.258157389636;2321.7330007049236;2325.616273314901;2333.568289866363;2341.976580234193;10309.742256443575;10334.689959301233;10385.2211084169;10403.5838566458;10550.419726915017;1900.809919008102;1961.4900125967208;2008.367364280247;2019.1014998325752;2024.0486971123548;64426.677866610364;65894.67052664753;66855.33144668589;67366.66316668385;68851.311486885;10754.677359679223;10896.291741036806;10947.890521094687;10956.945215274032;10975.451227438756;14520.927395362956;14571.562853114423;14583.197924114276;14691.11853288806;14697.77953330701;8225.71210007647;8244.793880153044;8307.584620768937;8308.667653293922;8334.708285209988;8012.719554815553;8135.6745730171315;8143.51138931666;8191.71329003494;8196.360727854453;4377.387165796791;4653.092646933816;4713.9629281558;4717.622590192776;4722.5513576210205;1813.8095499972576;1831.5692133300724;1908.5896532245633;1918.2518817661123;1927.9710804337947;922.2115091596722;955.3312681123242;995.875515560555;1009.5911155981839;1073.5866691323897;14481.348339324755;16645.583860403585;16738.497845064667;17140.65718685619;17447.651046979245;1020.2807021050141;1029.5367084811865;1048.3238311289329;1075.5052675769234;1116.190761697779;9585.952070239744;10266.384016959006;10295.227857910537;10309.639162629272;10331.276810623183;480110;481261;488051;566167;739708;87098.56450717832;91095.54452227696;93447;93978;94110;1201.5914589039594;1216.197309775544;1235.0798655002081;1242.9067819913544;1246.7818131826893;10756.731081723019;11154.163437742363;11204.71988200269;16448.34206631769;16686.83313166853;36007.63992360043;37014.62985370112;37746.49014039517;39143.60856391399;39530.40704389494;52147;52401.213981789544;54097.188542172684;54939.72530137324;58303.4169658298;15013.624659383602;15573.688526229638;16336.183190840173;16513.422030228885;16615.169241538115;18193;18603.348882789036;18762.812371876105;18853.15160817739;19181.424557263304;15466.613334666274;15501.612459688147;15850.286737096627;16000.199990000221;16014.439494617633;16229.193182101864;16408.17959102064;16570.83429165741;16581.419650312182;16670.499885003468;16175.919120404322;16269.105199214157;16285;17003.06483143439;17273.91363043177;15490.612734681723;15567.610809729847;15777.605559860636;15947.36210551565;16079.67840643204;15323.080615163126;15377.846221537642;15630.296636651605;15764.68470630604;15883.364665413254 06:15:29 INFO - 2016-02-20 06:15:29,448 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1583.303346527527;1601.3674598533578;1659.742739875319;1796.5149409659393;1867.3744295660954;7501.099868015832;7852.685892564297;7875;7877.7636670899965;7879.369650427966;7755.069391672993;7830.686772529092;7846.843063138734;7847.843043139133;7850.607469626524;2230.4200907764016;2255.706758121442;2293;2294.827887908405;2296.8392212545127;2331.0442718485424;2341.91803286885;2352.023910077324;2352.882355882208;2354.1642716835536;2006.7090271910565;2053.9486512837148;2246.8314876384256;2280.794728474437;2321.9303420897318;2314.097501974223;2320.4082958845556;2338.0764597983703;2340.379799353173;2340.4967931894553;2281.3042022183226;2312.7687231276905;2314.6065168921323;2315.895784689693;2315.988420057906;7037.6481175941;7243.130824301065;7418;7602.733904313378;7610.277023682741;4699.2481203007355;4796.880077998043;4797.472278049412;4798.640101992364;4801.1117943180625;2985.044785668587;2990.6710261871176;3013.2617508710505;3019.1697283247063;3041.148478426044;1007.3150257824676;1014.3204053284318;1028.9279750417475;1031.6079889641887;1050.7320633238476;10597.470126493645;10656.360618362924;10657.147428205724;10674;10689.625863094832;4178.811953462102;4214.325707886785;4256.893577660583;4269.167512335127;4279.443672322641;9306.488143152192;9314.534273286308;9320.580573874191;9341.439513629204;9348.345615806827;4747.715137091785;4750.667453278306;4784.569388755029;4789.544993225638;4793.592544633745;4331.675124365622;4386.1447017831215;4386.276264416336;4387.890302742457;4388.956110438855;3639.3813051781262;3685.649863262986;3687.575928768226;3707.5921648618632;3714.7213958953184;1085.6417382263835;1087.4345340422935;1088.7495875948575;1095.4851219926622;1098.4507746126937;1392.157744564524;1422.8719415252676;1434.397553027731;1463.4292625875917;1479.4599971010505;7401.703931842666;7462.813429664193;7465.104187497535;7482.251774822583;7491.8501629967095;489.48848453366315;492.4730538323959;493.80494704592206;494.0736119775421;503.7531609511313;5903.2325797646035;5964.85087872798;6075.544334174954;6078.635281883101;6080.391960803972;8452.535110568855;9536.570854311572;9546.42721436702;9555.474448905377;9587.424754514752;9150.954245228864;9222.723318300461;9345.158935695685;9390.34267601275;9394.013628569039;7454.515456495311;7454.589997550078;7543.773686789295;7545.245475452521;7548.320651141314;7290.708371665074;7455.403567714461;7607;7618.276263754934;7646.541207527566;8297.751067467985;8339.916600833914;8357.791055223668;8587.05542390349;8589.65641374338;8418;8496.06543280251;8596.78508037304;8684;8838.07200243965;7155.749548765768;7265.273472652692;7269.927300726925;7272.927270727225;7281.745138920324;7514.211007844317;7544.094708634999;7587.165411804586;7747.535147891144;7749.108852482037;7143.249958754256;7192.532485388433;7207.459440541876;7279.526830755984;7279.781606551812;6911.274316196727;7113.786586402416;7199.424046076406;7262.31008054234;7291.161516425679;7195.928040719526;7201.279872012967;7242.529235599669;7257.020302259128;7264.091988501437;6429.4856411486035;6580.572262803094;6590.604563726191;6593.406593406617;6595;6480.384363485461;6532.150820393318;6533.575317604341;6547.1161393211305;6559.409653131241 06:15:29 INFO - 2016-02-20 06:15:29,449 DEBUG : BROWSER_OUTPUT: |4;prototype.html;348.98778542750995;360.9765365251259;365.9469376940344;367.94112941929296;372.4841095083309;5987.55093367997;6021.488173505252;6029.397060293968;6059.212302400687;6140.692965351725;5854.7072646367615;6018.217631707883;6032.969835150829;6042.637441753498;6067.089936509526;4755.9286610700865;4756.143894099061;4756.262779269219;4756.9286460703115;4762.666613337068;4565.794539245725;4625.791839367236;4676.696014759047;4709.599684026862;4750.216214324632;4685.3206285088645;4689.484156742756;4701.694389864665;4705.200115980291;4712.316714076457;4684.625229981593;4693.014466961925;4693.812247510095;4702.905941881178;4704.247320428715;4172.687048471346;4176.540580536139;4177.624013838769;4185.602367775056;4194.266003449384;5675.631083979528;5777.855553611152;5778.451047150514;5783.652980821164;5785.62393444429;5468.0977638689565;5472.398036216013;5477.780888764445;5479.698616576086;5489.3412790465;6050.092486127072;6380.521460890452;6393.07300441438;6397.968010159966;6398.392152745481;4455.777211139462;4500.054988452429;4504.774761261924;4516.23224051912;4534.841280555198;6638.6680665966505;6650.83372915681;6653.002049692536;6658.334166583351;6669.43309818666;4616.699914505613;4626.398568186114;4630.189716799547;4633.49031606523;4633.745144017112;4603.677742558056;4675.696079754805;4677.134730074947;4685.8594242172785;4692.296155576691;5368.812091576776;5379.973100134474;5382.569394448435;5384.461553844584;5388.38033626138;6462.4183823456115;6462.545927166444;6464.385883341075;6470.352964703586;6483.870322593521;1680.9411670591471;1702.7105392083377;1703.931842726302;1705.5650809043861;1706.206613924531;1064.0583083970741;1065.9147268218524;1084.2572837606265;1086.6359769477156;1174.618249069056;6139.324674285825;6235.96882015587;6254.937450625526;6255.530835187379;6263.592866463665;6542.54202205841;6638.137042184485;6650.301718319603;6652.600843949379;6664.966675166593;6227.1282020517165;6246.656433896089;6249.6562689052535;6256.812295631138;6266.185395898504;3574;3611.4582812578324;3612.692921101683;3613.385724426854;3613.638636136365;6838.93161068383;6852.417544508673;6852.6916288767125;6858.725650974005;6858.828529286808;6576;6714.764983225564;6722;6723.22682891464;6728.764493242713;6828.419584335387;6835;6842.4868134890085;6845.281245469254;6848.554843935129;6496.090547323473;6628.966855165693;6648.235452922976;6656.600603963778;6660.966695166687;6644.235912870082;6669.566478178903;6671.199456065303;6671.232808227116;6678.332166783282;6967.059446974594;6969.6515174242095;6970.65146742671;6970.686319115551;6980.092587963532;4453.710508816916;4556.134334476439;4568.862934111982;4616.746078965689;4624.190766615761;6723.361280678328;6727.192736871607;6731.158605174353;6733.158355205599;6733.831654208684;6531.542792004514;6533.542652014314;6543.967280163569;6545;6549.115869357575;6445.519192158979;6478.190226221723;6526.477881769352;6527.608343499405;6536.8365790855605;6403.135576697273;6406.679666016774;6409.294977552372;6411.51913606472;6411.71147298382;6550.508711846535;6564.671766411565;6566.540342176002;6566.80299591013;6573.1454910861285;6280.591761535486;6418.935810641834;6458.257300410513;6463.289038205699;6464 06:15:29 INFO - 2016-02-20 06:15:29,450 DEBUG : BROWSER_OUTPUT: |5;yui.html;819.8153667949813;832.637802555888;841.941064125511;901.3014913442086;908.5684299957521;14340.4980825671;14636;14663.926680366583;14664.926675366622;14678.92660536697;13257.668558286048;13310.201387916732;13338;13357.53248636298;13379.531716389929;754.35125791819;757.8749506331476;905.6649039855263;918.6646873891049;926.8470702334107;926.0507979321216;935.4995077633467;937.4890684576922;944.2917811641269;945.3004776465435;960.0687333286701;966.9081437263449;968.7287559483356;969.0696930946265;969.9466529340883;787.3307688464788;792.5918152151618;958.856171574266;968.6077138758814;969.2246203037541;712.5653351455608;717.7416130193126;720.3732752505301;721.2499001038914;722.0180554445949;13667.521636742666;13789.241591712453;13834.654133646638;13834.723305533833;13846.100003499841;9828.606855725762;11070.944645276722;11911.166218364719;12130.636080917498;12152.878471215263;10579.629712960086;10705.732356691145;10706.946465267702;10737.570497180104;10937.56249750009;3811.00913762421;3837.347650899354;3853.441251018615;3859.614038596146;3859.710521710883;47680.52319476796;48258.51741482575;48311.75844120757;48880.26679599809;48966;10087.293889427816;10679.83980240297;10692.572297108185;10754.677359679066;10879.347239165676;18548.165332560067;19118.139683714267;19162;19163.904180479007;19187.520311992314;7769.961150194213;7848.960755196187;7937.801554961172;7986.480878742863;8014.879776803353;4515.706479078849;4562.8631141065825;4575.153596584642;4718.787654555554;4724.692894961817;3215.758818088653;3230.402375560529;3237.045071703853;3237.1583388319204;3242.3839470501002;571.8541771848152;580.1732531143105;580.4021857486796;580.8373655376502;604.1964187630441;676.509530590318;683.2143035509105;684.6302996381899;692.9480288978347;717.0176857704978;8915.821683566292;9331.860022099674;9333.813323733488;9358.391704539183;9400.952995235117;662.8641128568631;685.6092027544352;686.4199751210219;733.5378711411777;738.5568658804782;4237.37672927482;4298.226319262562;4378.7372757634;4477.955220447819;4479.910401792011;61163.38836611666;62604;62713;62830.68584657047;62896.371036289966;55887.44112558904;55963.16055259174;56087.87824243492;56814.715926420104;57072.14391784126;50211;50444.24333634999;50911.49088509067;51051.48948510542;51190;8692.739217823475;8776.122387761172;8936.151065648754;8940.955295223483;8976.640934362553;35530.11174720549;35709.46435803517;36168;36193.45709814408;36460;46357;49119.26321105115;49506.75246623744;49598.75200623974;49867.50397488081;11445.370504622326;11691.240069395462;12036.819447708467;12108.091893107876;12191.146619736533;12877.162984405984;13089.607311780663;13510.594682159552;13695.931520342334;14218.217998010208;12503.937480312541;12512.499500019898;12581.182223155465;12638.66917390487;13142.080054396483;12549.372531373578;12635.684107897376;12750.744985100431;13003.934980325415;13257.07200495956;11718.589849355232;11861.644150675493;12015.699607509881;12473.5010599575;12726.554570589984;9642.325037247607;9725.221982241545;10048.899511004796;10064.597416103274;10274.948625257124;9784.168345690412;9902.257330700082;9967.850482242628;10192.592296308065;10212.591496340361 06:15:29 INFO - 2016-02-20 06:15:29,450 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:15:29 INFO - 2016-02-20 06:15:29,450 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:15:29 INFO - 2016-02-20 06:15:29,450 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,861 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: __startTimestamp1455977729438__endTimestamp 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5537.61 Median:5746.00 stddev:624.04 (10.9%) stddev-sans-first:599.52 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: Values: 3247.0 3293.5 3319.0 3319.9 3341.1 5439.8 5494.5 5496.6 5511.3 5521.6 5453.1 5469.2 5481.7 5497.9 5508.6 4901.1 4983.7 5010.2 5032.1 5039.2 5049.5 5114.8 5114.9 5115.3 5117.8 5002.7 5054.2 5093.6 5117.1 5120.7 5062.2 5147.4 5151.8 5152.5 5158.9 4973.2 5075.4 5094.5 5115.2 5166.3 5254.7 5319.3 5345.0 5395.6 5442.1 5891.3 5987.7 6037.4 6049.8 6059.9 5977.7 6037.3 6069.8 6075.3 6077.7 5392.4 5481.8 5573.4 5642.1 5680.6 6069.8 6070.6 6073.1 6077.0 6096.4 5703.9 5907.7 5910.6 5910.9 5919.8 5948.3 5980.6 6064.0 6066.5 6083.7 5977.8 6007.7 6019.4 6021.3 6025.5 5746.0 5791.7 6015.8 6025.5 6027.1 4523.5 4527.7 4546.3 4596.9 4618.9 4043.3 4051.5 4068.7 4076.2 4083.0 5465.3 5614.9 5618.4 5629.5 5635.4 5877.2 5901.4 5949.7 5955.1 5985.1 5611.3 5727.2 5748.3 5754.4 5755.0 5025.6 5121.3 5259.1 5286.2 5287.7 6023.8 6130.1 6156.3 6163.1 6166.2 5977.0 5979.1 5984.7 6003.7 6037.5 5841.9 5963.6 6076.9 6085.8 6129.7 6015.5 6017.3 6017.4 6024.7 6031.6 6094.1 6123.4 6125.3 6125.9 6126.1 5662.7 5763.3 5767.8 5874.3 5932.7 5802.9 5830.7 5832.1 5834.7 5836.0 5719.3 5721.0 5730.7 5730.8 5731.0 5588.3 5624.9 5695.6 5804.4 5839.0 5746.6 5785.7 5797.1 5798.9 5799.7 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,451 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54188.14 Median:38785.61 stddev:93854.14 (242.0%) stddev-sans-first:94031.12 06:15:29 INFO - 2016-02-20 06:15:29,453 DEBUG : BROWSER_OUTPUT: Values: 582.4 613.6 630.6 634.6 638.2 30341.0 41094.0 41353.2 41358.0 41381.8 46009.0 46738.0 46812.5 46945.8 46978.8 7910.2 8043.8 9027.7 9179.2 9183.3 10856.9 10972.2 11035.0 11079.7 11088.1 10745.0 10812.8 10849.1 10863.7 10865.2 10743.7 10805.6 10848.2 10855.5 10858.5 10668.4 10783.7 10796.9 10805.1 10811.4 42579.0 42693.0 42928.6 42947.6 42966.8 3692.6 3756.8 3918.3 3980.6 4133.4 30712.8 30976.2 31126.5 31159.5 31807.0 4000.1 4163.3 4248.9 4273.9 4311.6 301950.0 302204.0 302303.0 302737.0 302787.0 59826.4 59958.7 64839.4 65235.0 72508.6 65686.3 65715.0 65759.3 66012.3 66027.0 17021.1 17109.7 17230.2 17245.2 17248.2 13748.5 13781.0 13813.6 13837.6 13839.7 21524.2 21786.5 21900.6 21987.6 21999.8 8109.3 8271.8 8343.5 8458.3 8572.4 1087.9 1098.2 1100.0 1120.7 1251.4 28318.9 28625.7 28863.9 29262.7 29289.9 1077.8 1093.8 1150.1 1190.9 1271.1 14809.8 16919.2 17755.7 17766.1 17902.1 429155.0 431992.0 434065.0 434544.0 436548.0 60218.1 60501.4 60685.0 60737.0 60802.4 1265.8 1311.2 1380.0 1400.6 1514.9 29416.9 29913.4 33190.7 34716.0 35373.0 53651.5 54777.0 54797.7 54850.7 54895.7 320593.0 321703.0 324502.0 325704.0 325888.0 43761.3 44392.3 44609.0 44965.6 45159.3 47383.0 47946.5 48085.0 48195.8 49366.5 40709.2 41215.8 41223.8 42458.6 42546.4 40808.2 40818.2 41460.4 43404.1 44144.2 38370.6 38630.4 39149.2 39276.2 39616.6 34272.5 38940.8 39029.0 39453.8 40321.4 26764.6 28147.0 28205.2 28578.7 29052.0 06:15:29 INFO - 2016-02-20 06:15:29,453 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,453 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30708.41 Median:1913.42 stddev:91716.46 (4793.3%) stddev-sans-first:91945.49 06:15:29 INFO - 2016-02-20 06:15:29,453 DEBUG : BROWSER_OUTPUT: Values: 387.5 387.9 389.0 389.4 391.3 14656.8 15998.4 17103.7 18377.9 18381.7 17021.0 17110.7 17334.0 17346.4 17474.1 6175.7 6298.7 6311.7 6313.3 6322.6 4543.0 4663.1 6243.2 6677.8 6909.7 5262.7 6840.4 6860.7 6890.0 6898.4 5423.8 5426.4 5429.9 5436.5 5445.5 5228.6 5260.5 5362.5 5364.9 5365.0 13332.4 13434.4 21541.5 21569.4 21687.7 2318.3 2321.7 2325.6 2333.6 2342.0 10309.7 10334.7 10385.2 10403.6 10550.4 1900.8 1961.5 2008.4 2019.1 2024.0 64426.7 65894.7 66855.3 67366.7 68851.3 10754.7 10896.3 10947.9 10956.9 10975.5 14520.9 14571.6 14583.2 14691.1 14697.8 8225.7 8244.8 8307.6 8308.7 8334.7 8012.7 8135.7 8143.5 8191.7 8196.4 4377.4 4653.1 4714.0 4717.6 4722.6 1813.8 1831.6 1908.6 1918.3 1928.0 922.2 955.3 995.9 1009.6 1073.6 14481.3 16645.6 16738.5 17140.7 17447.7 1020.3 1029.5 1048.3 1075.5 1116.2 9586.0 10266.4 10295.2 10309.6 10331.3 480110.0 481261.0 488051.0 566167.0 739708.0 87098.6 91095.5 93447.0 93978.0 94110.0 1201.6 1216.2 1235.1 1242.9 1246.8 10756.7 11154.2 11204.7 16448.3 16686.8 36007.6 37014.6 37746.5 39143.6 39530.4 52147.0 52401.2 54097.2 54939.7 58303.4 15013.6 15573.7 16336.2 16513.4 16615.2 18193.0 18603.3 18762.8 18853.2 19181.4 15466.6 15501.6 15850.3 16000.2 16014.4 16229.2 16408.2 16570.8 16581.4 16670.5 16175.9 16269.1 16285.0 17003.1 17273.9 15490.6 15567.6 15777.6 15947.4 16079.7 15323.1 15377.8 15630.3 15764.7 15883.4 06:15:29 INFO - 2016-02-20 06:15:29,453 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,453 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5522.53 Median:6454.94 stddev:2867.60 (44.4%) stddev-sans-first:2860.36 06:15:29 INFO - 2016-02-20 06:15:29,453 DEBUG : BROWSER_OUTPUT: Values: 1583.3 1601.4 1659.7 1796.5 1867.4 7501.1 7852.7 7875.0 7877.8 7879.4 7755.1 7830.7 7846.8 7847.8 7850.6 2230.4 2255.7 2293.0 2294.8 2296.8 2331.0 2341.9 2352.0 2352.9 2354.2 2006.7 2053.9 2246.8 2280.8 2321.9 2314.1 2320.4 2338.1 2340.4 2340.5 2281.3 2312.8 2314.6 2315.9 2316.0 7037.6 7243.1 7418.0 7602.7 7610.3 4699.2 4796.9 4797.5 4798.6 4801.1 2985.0 2990.7 3013.3 3019.2 3041.1 1007.3 1014.3 1028.9 1031.6 1050.7 10597.5 10656.4 10657.1 10674.0 10689.6 4178.8 4214.3 4256.9 4269.2 4279.4 9306.5 9314.5 9320.6 9341.4 9348.3 4747.7 4750.7 4784.6 4789.5 4793.6 4331.7 4386.1 4386.3 4387.9 4389.0 3639.4 3685.6 3687.6 3707.6 3714.7 1085.6 1087.4 1088.7 1095.5 1098.5 1392.2 1422.9 1434.4 1463.4 1479.5 7401.7 7462.8 7465.1 7482.3 7491.9 489.5 492.5 493.8 494.1 503.8 5903.2 5964.9 6075.5 6078.6 6080.4 8452.5 9536.6 9546.4 9555.5 9587.4 9151.0 9222.7 9345.2 9390.3 9394.0 7454.5 7454.6 7543.8 7545.2 7548.3 7290.7 7455.4 7607.0 7618.3 7646.5 8297.8 8339.9 8357.8 8587.1 8589.7 8418.0 8496.1 8596.8 8684.0 8838.1 7155.7 7265.3 7269.9 7272.9 7281.7 7514.2 7544.1 7587.2 7747.5 7749.1 7143.2 7192.5 7207.5 7279.5 7279.8 6911.3 7113.8 7199.4 7262.3 7291.2 7195.9 7201.3 7242.5 7257.0 7264.1 6429.5 6580.6 6590.6 6593.4 6595.0 6480.4 6532.2 6533.6 6547.1 6559.4 06:15:29 INFO - 2016-02-20 06:15:29,454 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,454 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5420.94 Median:6140.01 stddev:1615.54 (26.3%) stddev-sans-first:1574.58 06:15:29 INFO - 2016-02-20 06:15:29,455 DEBUG : BROWSER_OUTPUT: Values: 349.0 361.0 365.9 367.9 372.5 5987.6 6021.5 6029.4 6059.2 6140.7 5854.7 6018.2 6033.0 6042.6 6067.1 4755.9 4756.1 4756.3 4756.9 4762.7 4565.8 4625.8 4676.7 4709.6 4750.2 4685.3 4689.5 4701.7 4705.2 4712.3 4684.6 4693.0 4693.8 4702.9 4704.2 4172.7 4176.5 4177.6 4185.6 4194.3 5675.6 5777.9 5778.5 5783.7 5785.6 5468.1 5472.4 5477.8 5479.7 5489.3 6050.1 6380.5 6393.1 6398.0 6398.4 4455.8 4500.1 4504.8 4516.2 4534.8 6638.7 6650.8 6653.0 6658.3 6669.4 4616.7 4626.4 4630.2 4633.5 4633.7 4603.7 4675.7 4677.1 4685.9 4692.3 5368.8 5380.0 5382.6 5384.5 5388.4 6462.4 6462.5 6464.4 6470.4 6483.9 1680.9 1702.7 1703.9 1705.6 1706.2 1064.1 1065.9 1084.3 1086.6 1174.6 6139.3 6236.0 6254.9 6255.5 6263.6 6542.5 6638.1 6650.3 6652.6 6665.0 6227.1 6246.7 6249.7 6256.8 6266.2 3574.0 3611.5 3612.7 3613.4 3613.6 6838.9 6852.4 6852.7 6858.7 6858.8 6576.0 6714.8 6722.0 6723.2 6728.8 6828.4 6835.0 6842.5 6845.3 6848.6 6496.1 6629.0 6648.2 6656.6 6661.0 6644.2 6669.6 6671.2 6671.2 6678.3 6967.1 6969.7 6970.7 6970.7 6980.1 4453.7 4556.1 4568.9 4616.7 4624.2 6723.4 6727.2 6731.2 6733.2 6733.8 6531.5 6533.5 6544.0 6545.0 6549.1 6445.5 6478.2 6526.5 6527.6 6536.8 6403.1 6406.7 6409.3 6411.5 6411.7 6550.5 6564.7 6566.5 6566.8 6573.1 6280.6 6418.9 6458.3 6463.3 6464.0 06:15:29 INFO - 2016-02-20 06:15:29,455 DEBUG : BROWSER_OUTPUT: 06:15:29 INFO - 2016-02-20 06:15:29,455 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14687.49 Median:47969.52 stddev:17141.30 (35.7%) stddev-sans-first:17157.75 06:15:29 INFO - 2016-02-20 06:15:29,455 DEBUG : BROWSER_OUTPUT: Values: 819.8 832.6 841.9 901.3 908.6 14340.5 14636.0 14663.9 14664.9 14678.9 13257.7 13310.2 13338.0 13357.5 13379.5 754.4 757.9 905.7 918.7 926.8 926.1 935.5 937.5 944.3 945.3 960.1 966.9 968.7 969.1 969.9 787.3 792.6 958.9 968.6 969.2 712.6 717.7 720.4 721.2 722.0 13667.5 13789.2 13834.7 13834.7 13846.1 9828.6 11070.9 11911.2 12130.6 12152.9 10579.6 10705.7 10706.9 10737.6 10937.6 3811.0 3837.3 3853.4 3859.6 3859.7 47680.5 48258.5 48311.8 48880.3 48966.0 10087.3 10679.8 10692.6 10754.7 10879.3 18548.2 19118.1 19162.0 19163.9 19187.5 7770.0 7849.0 7937.8 7986.5 8014.9 4515.7 4562.9 4575.2 4718.8 4724.7 3215.8 3230.4 3237.0 3237.2 3242.4 571.9 580.2 580.4 580.8 604.2 676.5 683.2 684.6 692.9 717.0 8915.8 9331.9 9333.8 9358.4 9401.0 662.9 685.6 686.4 733.5 738.6 4237.4 4298.2 4378.7 4478.0 4479.9 61163.4 62604.0 62713.0 62830.7 62896.4 55887.4 55963.2 56087.9 56814.7 57072.1 50211.0 50444.2 50911.5 51051.5 51190.0 8692.7 8776.1 8936.2 8941.0 8976.6 35530.1 35709.5 36168.0 36193.5 36460.0 46357.0 49119.3 49506.8 49598.8 49867.5 11445.4 11691.2 12036.8 12108.1 12191.1 12877.2 13089.6 13510.6 13695.9 14218.2 12503.9 12512.5 12581.2 12638.7 13142.1 12549.4 12635.7 12750.7 13003.9 13257.1 11718.6 11861.6 12015.7 12473.5 12726.6 9642.3 9725.2 10048.9 10064.6 10274.9 9784.2 9902.3 9967.9 10192.6 10212.6 06:15:29 INFO - 2016-02-20 06:15:29,455 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:15:29 INFO - 2016-02-20 06:15:29,455 DEBUG : BROWSER_OUTPUT: 06:15:30 INFO - 2016-02-20 06:15:30,029 INFO : Browser exited with error code: 0 06:15:30 INFO - 2016-02-20 06:15:30,052 INFO : Completed test dromaeo_css (00:18:29) 06:15:30 INFO - 2016-02-20 06:15:30,053 INFO : Starting test kraken 06:15:30 INFO - 2016-02-20 06:15:30,053 DEBUG : operating with platform_type : linux_ 06:15:30 INFO - 2016-02-20 06:15:30,053 INFO : Initialising browser for kraken test... 06:15:30 INFO - 2016-02-20 06:15:30,059 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPZXtqI/profile http://localhost:41711/getInfo.html 06:15:33 INFO - 2016-02-20 06:15:33,775 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:15:33 INFO - 2016-02-20 06:15:33,775 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:15:33 INFO - 2016-02-20 06:15:33,775 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:15:33 INFO - 2016-02-20 06:15:33,775 DEBUG : BROWSER_OUTPUT: __metrics 06:15:33 INFO - 2016-02-20 06:15:33,937 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:15:34 INFO - 2016-02-20 06:15:34,821 INFO : Browser initialized. 06:15:34 INFO - 2016-02-20 06:15:34,822 INFO : Running cycle 1/1 for kraken test... 06:15:34 INFO - 2016-02-20 06:15:34,822 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPZXtqI/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:15:46 INFO - 2016-02-20 06:15:46,590 DEBUG : BROWSER_OUTPUT: RSS: Main: 132575232 06:15:46 INFO - 2016-02-20 06:15:46,590 DEBUG : BROWSER_OUTPUT: 06:15:49 INFO - 2016-02-20 06:15:49,010 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41711/tests/kraken/driver.html?testName=audio-beat-detection) 06:15:49 INFO - 2016-02-20 06:15:49,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 164089856 06:15:49 INFO - 2016-02-20 06:15:49,584 DEBUG : BROWSER_OUTPUT: 06:15:52 INFO - 2016-02-20 06:15:52,133 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41711/tests/kraken/driver.html?testName=audio-dft) 06:15:52 INFO - 2016-02-20 06:15:52,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 163008512 06:15:52 INFO - 2016-02-20 06:15:52,699 DEBUG : BROWSER_OUTPUT: 06:15:55 INFO - 2016-02-20 06:15:55,497 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41711/tests/kraken/driver.html?testName=audio-fft) 06:15:56 INFO - 2016-02-20 06:15:56,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 158285824 06:15:56 INFO - 2016-02-20 06:15:56,059 DEBUG : BROWSER_OUTPUT: 06:15:58 INFO - 2016-02-20 06:15:58,036 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41711/tests/kraken/driver.html?testName=audio-oscillator) 06:15:58 INFO - 2016-02-20 06:15:58,610 DEBUG : BROWSER_OUTPUT: RSS: Main: 159735808 06:15:58 INFO - 2016-02-20 06:15:58,610 DEBUG : BROWSER_OUTPUT: 06:16:00 INFO - 2016-02-20 06:16:00,784 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41711/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:16:01 INFO - 2016-02-20 06:16:01,345 DEBUG : BROWSER_OUTPUT: RSS: Main: 158986240 06:16:01 INFO - 2016-02-20 06:16:01,346 DEBUG : BROWSER_OUTPUT: 06:16:04 INFO - 2016-02-20 06:16:04,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41711/tests/kraken/driver.html?testName=imaging-darkroom) 06:16:04 INFO - 2016-02-20 06:16:04,810 DEBUG : BROWSER_OUTPUT: RSS: Main: 158343168 06:16:04 INFO - 2016-02-20 06:16:04,810 DEBUG : BROWSER_OUTPUT: 06:16:08 INFO - 2016-02-20 06:16:08,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41711/tests/kraken/driver.html?testName=imaging-desaturate) 06:16:08 INFO - 2016-02-20 06:16:08,746 DEBUG : BROWSER_OUTPUT: RSS: Main: 159064064 06:16:08 INFO - 2016-02-20 06:16:08,746 DEBUG : BROWSER_OUTPUT: 06:16:11 INFO - 2016-02-20 06:16:11,457 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41711/tests/kraken/driver.html?testName=json-parse-financial) 06:16:12 INFO - 2016-02-20 06:16:12,024 DEBUG : BROWSER_OUTPUT: RSS: Main: 158920704 06:16:12 INFO - 2016-02-20 06:16:12,024 DEBUG : BROWSER_OUTPUT: 06:16:13 INFO - 2016-02-20 06:16:13,584 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41711/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:16:14 INFO - 2016-02-20 06:16:14,147 DEBUG : BROWSER_OUTPUT: RSS: Main: 165928960 06:16:14 INFO - 2016-02-20 06:16:14,147 DEBUG : BROWSER_OUTPUT: 06:16:15 INFO - 2016-02-20 06:16:15,920 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:16:16 INFO - 2016-02-20 06:16:16,509 DEBUG : BROWSER_OUTPUT: RSS: Main: 164446208 06:16:16 INFO - 2016-02-20 06:16:16,510 DEBUG : BROWSER_OUTPUT: 06:16:18 INFO - 2016-02-20 06:16:18,462 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:16:19 INFO - 2016-02-20 06:16:19,032 DEBUG : BROWSER_OUTPUT: RSS: Main: 168366080 06:16:19 INFO - 2016-02-20 06:16:19,032 DEBUG : BROWSER_OUTPUT: 06:16:21 INFO - 2016-02-20 06:16:21,411 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:16:21 INFO - 2016-02-20 06:16:21,992 DEBUG : BROWSER_OUTPUT: RSS: Main: 176824320 06:16:21 INFO - 2016-02-20 06:16:21,993 DEBUG : BROWSER_OUTPUT: 06:16:24 INFO - 2016-02-20 06:16:24,458 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:16:25 INFO - 2016-02-20 06:16:25,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 173658112 06:16:25 INFO - 2016-02-20 06:16:25,026 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,696 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41711/tests/kraken/driver.html?testName=ai-astar) 06:16:26 INFO - 2016-02-20 06:16:26,940 DEBUG : BROWSER_OUTPUT: RSS: Main: 233402368 06:16:26 INFO - 2016-02-20 06:16:26,940 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,944 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:16:26 INFO - 2016-02-20 06:16:26,944 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:16:26 INFO - 2016-02-20 06:16:26,944 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:16:26 INFO - 2016-02-20 06:16:26,944 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:16:26 INFO - 2016-02-20 06:16:26,944 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;102;108;112;109;99;107;108;108 06:16:26 INFO - 2016-02-20 06:16:26,944 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;123;153;158;124;144;147;122;158;123 06:16:26 INFO - 2016-02-20 06:16:26,944 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;161;160;160;161;160;162;161;160 06:16:26 INFO - 2016-02-20 06:16:26,945 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;71;149;72;71;72;73;147;73 06:16:26 INFO - 2016-02-20 06:16:26,945 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;91;134;91;92;93;130;91;92;133 06:16:26 INFO - 2016-02-20 06:16:26,945 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;112;109;109;109;110;109;110;109;109 06:16:26 INFO - 2016-02-20 06:16:26,945 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;147;146;147;146;146;144 06:16:26 INFO - 2016-02-20 06:16:26,945 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 06:16:26 INFO - 2016-02-20 06:16:26,945 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;58;58;59;58;58;59;55;57 06:16:26 INFO - 2016-02-20 06:16:26,945 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;64;65;65;65;65;64;65;65 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;78;78;78;78;77;78;80;79;78 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;111;112;112;121;118;118;116;110;116;137 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;132;141;140;140;144;131;141;146;141;133 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;65;63;64;63;63;63;64;63;62 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,902 06:16:26 INFO - 2016-02-20 06:16:26,946 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:16:26 INFO - 2016-02-20 06:16:26,947 DEBUG : BROWSER_OUTPUT: __startTimestamp1455977786942__endTimestamp 06:16:26 INFO - 2016-02-20 06:16:26,947 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,954 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:108.50 stddev:3.74 (3.4%) stddev-sans-first:3.95 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 102.0 108.0 112.0 109.0 99.0 107.0 108.0 108.0 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.90 Median:150.00 stddev:16.06 (10.7%) stddev-sans-first:15.94 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: Values: 157.0 123.0 153.0 158.0 124.0 144.0 147.0 122.0 158.0 123.0 06:16:26 INFO - 2016-02-20 06:16:26,955 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 161.0 160.0 160.0 161.0 160.0 162.0 161.0 160.0 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.20 Median:72.00 stddev:32.05 (44.5%) stddev-sans-first:33.52 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 71.0 149.0 72.0 71.0 72.0 73.0 147.0 73.0 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.80 Median:91.00 stddev:19.72 (21.7%) stddev-sans-first:20.37 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: Values: 91.0 91.0 134.0 91.0 92.0 93.0 130.0 91.0 92.0 133.0 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:109.50 stddev:0.97 (0.9%) stddev-sans-first:1.01 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: Values: 110.0 112.0 109.0 109.0 109.0 110.0 109.0 110.0 109.0 109.0 06:16:26 INFO - 2016-02-20 06:16:26,956 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 147.0 146.0 147.0 146.0 146.0 144.0 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:1.17 (2.0%) stddev-sans-first:1.22 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 58.0 58.0 59.0 58.0 58.0 59.0 55.0 57.0 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:65.00 stddev:0.71 (1.1%) stddev-sans-first:0.50 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 65.0 65.0 65.0 65.0 64.0 65.0 65.0 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:78.00 stddev:0.79 (1.0%) stddev-sans-first:0.83 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: Values: 78.0 78.0 78.0 78.0 78.0 77.0 78.0 80.0 79.0 78.0 06:16:26 INFO - 2016-02-20 06:16:26,957 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:117.00 stddev:7.85 (6.7%) stddev-sans-first:8.01 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: Values: 111.0 112.0 112.0 121.0 118.0 118.0 116.0 110.0 116.0 137.0 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.90 Median:141.00 stddev:5.13 (3.6%) stddev-sans-first:4.80 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: Values: 132.0 141.0 140.0 140.0 144.0 131.0 141.0 146.0 141.0 133.0 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 63.0 64.0 63.0 63.0 63.0 64.0 63.0 62.0 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:16:26 INFO - 2016-02-20 06:16:26,958 DEBUG : BROWSER_OUTPUT: 06:16:27 INFO - 2016-02-20 06:16:27,594 INFO : Browser exited with error code: 0 06:16:27 INFO - 2016-02-20 06:16:27,650 INFO : Completed test kraken (00:00:57) 06:16:27 INFO - 2016-02-20 06:16:27,650 INFO : Starting test v8_7 06:16:27 INFO - 2016-02-20 06:16:27,650 DEBUG : operating with platform_type : linux_ 06:16:27 INFO - 2016-02-20 06:16:27,650 INFO : Initialising browser for v8_7 test... 06:16:27 INFO - 2016-02-20 06:16:27,656 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyphwZl/profile http://localhost:41711/getInfo.html 06:16:31 INFO - 2016-02-20 06:16:31,102 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:16:31 INFO - 2016-02-20 06:16:31,102 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:16:31 INFO - 2016-02-20 06:16:31,102 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:16:31 INFO - 2016-02-20 06:16:31,102 DEBUG : BROWSER_OUTPUT: __metrics 06:16:31 INFO - 2016-02-20 06:16:31,247 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:16:32 INFO - 2016-02-20 06:16:32,265 INFO : Browser initialized. 06:16:32 INFO - 2016-02-20 06:16:32,266 INFO : Running cycle 1/1 for v8_7 test... 06:16:32 INFO - 2016-02-20 06:16:32,266 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyphwZl/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:16:43 INFO - 2016-02-20 06:16:43,931 DEBUG : BROWSER_OUTPUT: RSS: Main: 133414912 06:16:43 INFO - 2016-02-20 06:16:43,931 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,268 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41711/tests/v8_7/run.html (next: http://localhost:41711/tests/v8_7/run.html) 06:17:05 INFO - 2016-02-20 06:17:05,519 DEBUG : BROWSER_OUTPUT: RSS: Main: 422232064 06:17:05 INFO - 2016-02-20 06:17:05,519 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,520 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:17:05 INFO - 2016-02-20 06:17:05,520 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:17:05 INFO - 2016-02-20 06:17:05,521 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:17:05 INFO - 2016-02-20 06:17:05,522 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:17:05 INFO - 2016-02-20 06:17:05,522 DEBUG : BROWSER_OUTPUT: |0;Richards;167.9261125104954 06:17:05 INFO - 2016-02-20 06:17:05,522 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;168.49199663016006 06:17:05 INFO - 2016-02-20 06:17:05,522 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.77777777777777 06:17:05 INFO - 2016-02-20 06:17:05,523 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5186.5284974093265 06:17:05 INFO - 2016-02-20 06:17:05,523 DEBUG : BROWSER_OUTPUT: |4;RayTrace;944.2870632672333 06:17:05 INFO - 2016-02-20 06:17:05,523 DEBUG : BROWSER_OUTPUT: |5;Earley;547.645125958379 06:17:05 INFO - 2016-02-20 06:17:05,524 DEBUG : BROWSER_OUTPUT: |6;Boyer;10536.842105263158 06:17:05 INFO - 2016-02-20 06:17:05,524 DEBUG : BROWSER_OUTPUT: |7;RegExp;26315.78947368421 06:17:05 INFO - 2016-02-20 06:17:05,524 DEBUG : BROWSER_OUTPUT: |8;Splay;511.5089514066496 06:17:05 INFO - 2016-02-20 06:17:05,524 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 06:17:05 INFO - 2016-02-20 06:17:05,525 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:17:05 INFO - 2016-02-20 06:17:05,525 DEBUG : BROWSER_OUTPUT: __startTimestamp1455977825519__endTimestamp 06:17:05 INFO - 2016-02-20 06:17:05,525 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,526 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:17:05 INFO - 2016-02-20 06:17:05,526 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:17:05 INFO - 2016-02-20 06:17:05,526 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,526 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.93 Median:167.93 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,527 DEBUG : BROWSER_OUTPUT: Values: 167.9 06:17:05 INFO - 2016-02-20 06:17:05,527 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,527 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:168.49 Median:168.49 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,527 DEBUG : BROWSER_OUTPUT: Values: 168.5 06:17:05 INFO - 2016-02-20 06:17:05,528 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,528 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.78 Median:277.78 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,528 DEBUG : BROWSER_OUTPUT: Values: 277.8 06:17:05 INFO - 2016-02-20 06:17:05,528 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,528 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,529 DEBUG : BROWSER_OUTPUT: Values: 5186.5 06:17:05 INFO - 2016-02-20 06:17:05,529 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,529 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:944.29 Median:944.29 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,529 DEBUG : BROWSER_OUTPUT: Values: 944.3 06:17:05 INFO - 2016-02-20 06:17:05,530 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,530 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:547.65 Median:547.65 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,530 DEBUG : BROWSER_OUTPUT: Values: 547.6 06:17:05 INFO - 2016-02-20 06:17:05,530 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,531 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10536.84 Median:10536.84 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,531 DEBUG : BROWSER_OUTPUT: Values: 10536.8 06:17:05 INFO - 2016-02-20 06:17:05,531 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,531 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26315.79 Median:26315.79 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,532 DEBUG : BROWSER_OUTPUT: Values: 26315.8 06:17:05 INFO - 2016-02-20 06:17:05,532 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,532 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:511.51 Median:511.51 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,532 DEBUG : BROWSER_OUTPUT: Values: 511.5 06:17:05 INFO - 2016-02-20 06:17:05,532 DEBUG : BROWSER_OUTPUT: 06:17:05 INFO - 2016-02-20 06:17:05,533 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 06:17:05 INFO - 2016-02-20 06:17:05,533 DEBUG : BROWSER_OUTPUT: Values: 4826.9 06:17:05 INFO - 2016-02-20 06:17:05,533 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:17:05 INFO - 2016-02-20 06:17:05,533 DEBUG : BROWSER_OUTPUT: 06:17:06 INFO - 2016-02-20 06:17:06,233 INFO : Browser exited with error code: 0 06:17:06 INFO - 2016-02-20 06:17:06,245 INFO : Completed test v8_7 (00:00:38) 06:17:06 INFO - 2016-02-20 06:17:06,557 INFO : Completed test suite (00:20:05) 06:17:06 INFO - 2016-02-20 06:17:06,557 DEBUG : Working with test: dromaeo_css 06:17:06 INFO - 2016-02-20 06:17:06,558 DEBUG : Generating results file: dromaeo_css 06:17:06 INFO - 2016-02-20 06:17:06,559 DEBUG : Working with test: kraken 06:17:06 INFO - 2016-02-20 06:17:06,559 DEBUG : Generating results file: kraken 06:17:06 INFO - 2016-02-20 06:17:06,559 INFO : javascript benchmark 06:17:06 INFO - 2016-02-20 06:17:06,559 DEBUG : Working with test: v8_7 06:17:06 INFO - 2016-02-20 06:17:06,559 DEBUG : Generating results file: v8_7 06:17:06 INFO - 2016-02-20 06:17:06,560 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:17:06 INFO - 2016-02-20 06:17:06,632 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 06:17:06 INFO - 2016-02-20 06:17:06,632 DEBUG : process_Request line: dromaeo_css 7409.10 graph.html#tests=[[72,53,35]] 06:17:06 INFO - 2016-02-20 06:17:06,632 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:17:06 INFO - 2016-02-20 06:17:06,765 DEBUG : process_Request line: kraken 1469.50 graph.html#tests=[[232,53,35]] 06:17:06 INFO - 2016-02-20 06:17:06,765 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:17:06 INFO - 2016-02-20 06:17:06,894 DEBUG : process_Request line: v8_7 23096.36 graph.html#tests=[[230,53,35]] 06:17:06 INFO - 2016-02-20 06:17:06,895 INFO : javascript benchmark 06:17:06 INFO - 2016-02-20 06:17:06,898 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3247.0096620530735, 3293.505974103886, 3319.0, 3319.850606722697, 3341.0812026692656, 5439.809606663767, 5494.478024587663, 5496.560275177986, 5511.338639363272, 5521.613487055908, 5453.072977593809, 5469.234307196995, 5481.671099734009, 5497.917531237024, 5508.64193827401, 4901.1177987962155, 4983.700977941335, 5010.198368261079, 5032.094223039851, 5039.168537191359, 5049.520295571915, 5114.846554603349, 5114.923276150861, 5115.258287548303, 5117.795288188468, 5002.699838009731, 5054.241863720435, 5093.617978651598, 5117.078925793356, 5120.667156634807, 5062.215356619729, 5147.38231412231, 5151.8196863109615, 5152.458991805881, 5158.871028224287, 4973.2291494818, 5075.390953085654, 5094.490550944913, 5115.2071428928575, 5166.302549155854, 5254.737263136866, 5319.308489896327, 5345.01117293302, 5395.622306438551, 5442.074847275972, 5891.322497912752, 5987.670678112702, 6037.396260373971, 6049.818505444844, 6059.939400605982, 5977.731002104916, 6037.335893051804, 6069.757209711606, 6075.301340345828, 6077.696115194267, 5392.379876314235, 5481.835544933659, 5573.41479144692, 5642.069058605366, 5680.573956953203, 6069.817905462754, 6070.6357618543025, 6073.058675905242, 6077.0, 6096.420840020191, 5703.9429605704245, 5907.734151963172, 5910.615809972338, 5910.911336329958, 5919.763209471659, 5948.315943666447, 5980.581359304807, 6064.0, 6066.45401913821, 6083.726232319556, 5977.790777322773, 6007.699615019232, 6019.367966363468, 6021.337652858182, 6025.517958563304, 5746.0, 5791.710414479259, 6015.789447369321, 6025.517958563304, 6027.065804800263, 4523.547645235451, 4527.660425468037, 4546.272596384562, 4596.908061838744, 4618.930716039262, 4043.251998380309, 4051.513818341818, 4068.6745060395688, 4076.164386300801, 4083.0404854859084, 5465.289512363447, 5614.915776263278, 5618.353889302701, 5629.521490673339, 5635.436456354331, 5877.23595932526, 5901.380355062743, 5949.672767997715, 5955.076963070731, 5985.132155837379, 5611.298587676541, 5727.198192253088, 5748.31020277561, 5754.367019627837, 5755.0, 5025.648204625714, 5121.308623335876, 5259.05337039329, 5286.20706893969, 5287.735613219324, 6023.759049638053, 6130.134249640431, 6156.322804491502, 6163.137160797492, 6166.167567378437, 5977.0, 5979.103134529855, 5984.730687119177, 6003.699815009145, 6037.486813620892, 5841.94158058431, 5963.552733544915, 6076.939230607637, 6085.786997455068, 6129.693515324305, 6015.4586087252355, 6017.308009578779, 6017.398260173947, 6024.668643224665, 6031.60794548353, 6094.11635312886, 6123.387661233841, 6125.326214116354, 6125.877482450415, 6126.111713801563, 5662.66024038559, 5763.279590051244, 5767.826965191051, 5874.265716785402, 5932.673702946379, 5802.854928626818, 5830.650160990354, 5832.066869300891, 5834.7082645866685, 5836.0, 5719.28508936383, 5721.027425337619, 5730.713464326684, 5730.828075157752, 5731.0, 5588.273524441796, 5624.915626265692, 5695.601307908407, 5804.419558044162, 5839.0, 5746.626469279484, 5785.739641716049, 5797.072468405035, 5798.855028624318, 5799.739011744566], "unit": "ms", "name": "dojo.html", "value": 5496.434007279193}, {"lowerIsBetter": false, "replicates": [582.4350380131272, 613.634887242091, 630.5680608782984, 634.5716641267146, 638.2086213095763, 30341.0, 41094.0, 41353.172936541254, 41357.99999999996, 41381.793091034575, 46009.0, 46738.0, 46812.53187468124, 46945.765271173594, 46978.76510617442, 7910.208979102073, 8043.839123217532, 9027.684031058914, 9179.219766319837, 9183.265338772882, 10856.891431085667, 10972.177086718488, 11035.0, 11079.72300692485, 11088.057515111224, 10745.0, 10812.783744325108, 10849.077828384558, 10863.728406789814, 10865.239433239676, 10743.677689669283, 10805.621803236849, 10848.18638602108, 10855.51150198243, 10858.457077146151, 10668.359898406121, 10783.676489705322, 10796.892031079746, 10805.081568066724, 10811.405372704497, 42579.0, 42693.0, 42928.57071429277, 42947.57052429467, 42966.78516607428, 3692.5753538342897, 3756.849726010956, 3918.2751191029465, 3980.5820388859042, 4133.441985331971, 30712.84643576768, 30976.225594360098, 31126.533102003486, 31159.532607010686, 31807.0, 4000.119973605802, 4163.3130533462245, 4248.87253382399, 4273.888788914874, 4311.61195492404, 301950.0, 302204.0, 302302.9999999978, 302737.0, 302787.0, 59826.40173598295, 59958.70020649869, 64839.35160648333, 65235.0, 72508.63745681237, 65686.34313656802, 65715.0, 65759.34240657532, 66012.33987660157, 66027.0, 17021.06384148859, 17109.657806843792, 17230.224639891236, 17245.22396492136, 17248.223829927683, 13748.518801841887, 13781.0, 13813.585592432242, 13837.584872453843, 13839.723205536035, 21524.246651367128, 21786.455338616663, 21900.561988760135, 21987.560248794933, 21999.780002200092, 8109.270165685117, 8271.751847444586, 8343.541105239148, 8458.28104611115, 8572.399932004824, 1087.9075278601408, 1098.1708809848512, 1099.9505022274018, 1120.6974116988367, 1251.393074358935, 28318.85840570784, 28625.713742862306, 28863.855680721463, 29262.707372926423, 29289.853550732536, 1077.7682798198268, 1093.8413929980268, 1150.0914277720508, 1190.922590031645, 1271.1038717703996, 14809.777853332209, 16919.23863426149, 17755.73366399505, 17766.111694415227, 17902.10489475547, 429155.0, 431992.0, 434065.0, 434544.0, 436548.0, 60218.096728549106, 60501.39498604958, 60685.0, 60737.0, 60802.39197608056, 1265.7595056939335, 1311.1936159262175, 1379.9862001380059, 1400.5588239704462, 1514.9091054536764, 29416.852915735286, 29913.401731965674, 33190.66809331876, 34716.0, 35373.0, 53651.46348536621, 54777.0, 54797.72601136969, 54850.72574637101, 54895.72552137214, 320593.0, 321703.0, 324502.0, 325704.0, 325888.0, 43761.34357984569, 44392.334114988946, 44609.0, 44965.550344496136, 45159.32261016153, 47383.0, 47946.5205347942, 48085.0, 48195.75902120467, 49366.50633493619, 40709.1858162841, 41215.79392103021, 41223.793881030404, 42458.5754142467, 42546.361804572334, 40808.15344129419, 40818.18363632651, 41460.378094329215, 43404.13191736211, 44144.23423063041, 38370.616293836705, 38630.4205436913, 39149.2170156601, 39276.214475709756, 39616.60383396129, 34272.48591271083, 38940.80529597334, 39029.0, 39453.802730986165, 40321.395179072926, 26764.59853102166, 28147.0, 28205.15384538467, 28578.714212857605, 29052.0], "unit": "ms", "name": "ext.html", "value": 20927.994813003494}, {"lowerIsBetter": false, "replicates": [387.50205985308884, 387.9185371072075, 389.0371330955884, 389.3595036165508, 391.2995737629643, 14656.780148297757, 15998.360065597362, 17103.74344384835, 18377.908110459444, 18381.724274135897, 17021.0, 17110.743338849927, 17334.046627435488, 17346.392876249334, 17474.126293685298, 6175.691215439232, 6298.748050077991, 6311.715972781236, 6313.305536391016, 6322.620642761426, 4542.9545704543025, 4663.114008338422, 6243.188385509878, 6677.833054173636, 6909.689063992108, 5262.736863156827, 6840.41856442203, 6860.725570977155, 6889.965550172242, 6898.448124150081, 5423.83728488146, 5426.430224826376, 5429.864253393657, 5436.456354364572, 5445.455454454563, 5228.581713462914, 5260.526552610283, 5362.4637536246455, 5364.946350536484, 5365.03429382711, 13332.400041998133, 13434.395452204673, 21541.461463463384, 21569.352919412442, 21687.67468487974, 2318.258157389636, 2321.7330007049236, 2325.616273314901, 2333.568289866363, 2341.976580234193, 10309.742256443575, 10334.689959301233, 10385.2211084169, 10403.5838566458, 10550.419726915017, 1900.809919008102, 1961.4900125967208, 2008.367364280247, 2019.1014998325752, 2024.0486971123548, 64426.677866610364, 65894.67052664753, 66855.33144668589, 67366.66316668385, 68851.311486885, 10754.677359679223, 10896.291741036806, 10947.890521094687, 10956.945215274032, 10975.451227438756, 14520.927395362956, 14571.562853114423, 14583.197924114276, 14691.11853288806, 14697.77953330701, 8225.71210007647, 8244.793880153044, 8307.584620768937, 8308.667653293922, 8334.708285209988, 8012.719554815553, 8135.6745730171315, 8143.51138931666, 8191.71329003494, 8196.360727854453, 4377.387165796791, 4653.092646933816, 4713.9629281558, 4717.622590192776, 4722.5513576210205, 1813.8095499972576, 1831.5692133300724, 1908.5896532245633, 1918.2518817661123, 1927.9710804337947, 922.2115091596722, 955.3312681123242, 995.875515560555, 1009.5911155981839, 1073.5866691323897, 14481.348339324755, 16645.583860403585, 16738.497845064667, 17140.65718685619, 17447.651046979245, 1020.2807021050141, 1029.5367084811865, 1048.3238311289329, 1075.5052675769234, 1116.190761697779, 9585.952070239744, 10266.384016959006, 10295.227857910537, 10309.639162629272, 10331.276810623183, 480110.0, 481261.0, 488051.0, 566167.0, 739708.0, 87098.56450717832, 91095.54452227696, 93447.0, 93978.0, 94110.0, 1201.5914589039594, 1216.197309775544, 1235.0798655002081, 1242.9067819913544, 1246.7818131826893, 10756.731081723019, 11154.163437742363, 11204.71988200269, 16448.34206631769, 16686.83313166853, 36007.63992360043, 37014.62985370112, 37746.49014039517, 39143.60856391399, 39530.40704389494, 52147.0, 52401.213981789544, 54097.188542172684, 54939.72530137324, 58303.4169658298, 15013.624659383602, 15573.688526229638, 16336.183190840173, 16513.422030228885, 16615.169241538115, 18193.0, 18603.348882789036, 18762.812371876105, 18853.15160817739, 19181.424557263304, 15466.613334666274, 15501.612459688147, 15850.286737096627, 16000.199990000221, 16014.439494617633, 16229.193182101864, 16408.17959102064, 16570.83429165741, 16581.419650312182, 16670.499885003468, 16175.919120404322, 16269.105199214157, 16285.0, 17003.06483143439, 17273.91363043177, 15490.612734681723, 15567.610809729847, 15777.605559860636, 15947.36210551565, 16079.67840643204, 15323.080615163126, 15377.846221537642, 15630.296636651605, 15764.68470630604, 15883.364665413254], "unit": "ms", "name": "jquery.html", "value": 9755.898683392388}, {"lowerIsBetter": false, "replicates": [1583.303346527527, 1601.3674598533578, 1659.742739875319, 1796.5149409659393, 1867.3744295660954, 7501.099868015832, 7852.685892564297, 7875.0, 7877.7636670899965, 7879.369650427966, 7755.069391672993, 7830.686772529092, 7846.843063138734, 7847.843043139133, 7850.607469626524, 2230.4200907764016, 2255.706758121442, 2293.0, 2294.827887908405, 2296.8392212545127, 2331.0442718485424, 2341.91803286885, 2352.023910077324, 2352.882355882208, 2354.1642716835536, 2006.7090271910565, 2053.9486512837148, 2246.8314876384256, 2280.794728474437, 2321.9303420897318, 2314.097501974223, 2320.4082958845556, 2338.0764597983703, 2340.379799353173, 2340.4967931894553, 2281.3042022183226, 2312.7687231276905, 2314.6065168921323, 2315.895784689693, 2315.988420057906, 7037.6481175941, 7243.130824301065, 7418.0, 7602.733904313378, 7610.277023682741, 4699.2481203007355, 4796.880077998043, 4797.472278049412, 4798.640101992364, 4801.1117943180625, 2985.044785668587, 2990.6710261871176, 3013.2617508710505, 3019.1697283247063, 3041.148478426044, 1007.3150257824676, 1014.3204053284318, 1028.9279750417475, 1031.6079889641887, 1050.7320633238476, 10597.470126493645, 10656.360618362924, 10657.147428205724, 10674.0, 10689.625863094832, 4178.811953462102, 4214.325707886785, 4256.893577660583, 4269.167512335127, 4279.443672322641, 9306.488143152192, 9314.534273286308, 9320.580573874191, 9341.439513629204, 9348.345615806827, 4747.715137091785, 4750.667453278306, 4784.569388755029, 4789.544993225638, 4793.592544633745, 4331.675124365622, 4386.1447017831215, 4386.276264416336, 4387.890302742457, 4388.956110438855, 3639.3813051781262, 3685.649863262986, 3687.575928768226, 3707.5921648618632, 3714.7213958953184, 1085.6417382263835, 1087.4345340422935, 1088.7495875948575, 1095.4851219926622, 1098.4507746126937, 1392.157744564524, 1422.8719415252676, 1434.397553027731, 1463.4292625875917, 1479.4599971010505, 7401.703931842666, 7462.813429664193, 7465.104187497535, 7482.251774822583, 7491.8501629967095, 489.48848453366315, 492.4730538323959, 493.80494704592206, 494.0736119775421, 503.7531609511313, 5903.2325797646035, 5964.85087872798, 6075.544334174954, 6078.635281883101, 6080.391960803972, 8452.535110568855, 9536.570854311572, 9546.42721436702, 9555.474448905377, 9587.424754514752, 9150.954245228864, 9222.723318300461, 9345.158935695685, 9390.34267601275, 9394.013628569039, 7454.515456495311, 7454.589997550078, 7543.773686789295, 7545.245475452521, 7548.320651141314, 7290.708371665074, 7455.403567714461, 7607.0, 7618.276263754934, 7646.541207527566, 8297.751067467985, 8339.916600833914, 8357.791055223668, 8587.05542390349, 8589.65641374338, 8418.0, 8496.06543280251, 8596.78508037304, 8684.0, 8838.07200243965, 7155.749548765768, 7265.273472652692, 7269.927300726925, 7272.927270727225, 7281.745138920324, 7514.211007844317, 7544.094708634999, 7587.165411804586, 7747.535147891144, 7749.108852482037, 7143.249958754256, 7192.532485388433, 7207.459440541876, 7279.526830755984, 7279.781606551812, 6911.274316196727, 7113.786586402416, 7199.424046076406, 7262.31008054234, 7291.161516425679, 7195.928040719526, 7201.279872012967, 7242.529235599669, 7257.020302259128, 7264.091988501437, 6429.4856411486035, 6580.572262803094, 6590.604563726191, 6593.406593406617, 6595.0, 6480.384363485461, 6532.150820393318, 6533.575317604341, 6547.1161393211305, 6559.409653131241], "unit": "ms", "name": "mootools.html", "value": 4468.072089906543}, {"lowerIsBetter": false, "replicates": [348.98778542750995, 360.9765365251259, 365.9469376940344, 367.94112941929296, 372.4841095083309, 5987.55093367997, 6021.488173505252, 6029.397060293968, 6059.212302400687, 6140.692965351725, 5854.7072646367615, 6018.217631707883, 6032.969835150829, 6042.637441753498, 6067.089936509526, 4755.9286610700865, 4756.143894099061, 4756.262779269219, 4756.9286460703115, 4762.666613337068, 4565.794539245725, 4625.791839367236, 4676.696014759047, 4709.599684026862, 4750.216214324632, 4685.3206285088645, 4689.484156742756, 4701.694389864665, 4705.200115980291, 4712.316714076457, 4684.625229981593, 4693.014466961925, 4693.812247510095, 4702.905941881178, 4704.247320428715, 4172.687048471346, 4176.540580536139, 4177.624013838769, 4185.602367775056, 4194.266003449384, 5675.631083979528, 5777.855553611152, 5778.451047150514, 5783.652980821164, 5785.62393444429, 5468.0977638689565, 5472.398036216013, 5477.780888764445, 5479.698616576086, 5489.3412790465, 6050.092486127072, 6380.521460890452, 6393.07300441438, 6397.968010159966, 6398.392152745481, 4455.777211139462, 4500.054988452429, 4504.774761261924, 4516.23224051912, 4534.841280555198, 6638.6680665966505, 6650.83372915681, 6653.002049692536, 6658.334166583351, 6669.43309818666, 4616.699914505613, 4626.398568186114, 4630.189716799547, 4633.49031606523, 4633.745144017112, 4603.677742558056, 4675.696079754805, 4677.134730074947, 4685.8594242172785, 4692.296155576691, 5368.812091576776, 5379.973100134474, 5382.569394448435, 5384.461553844584, 5388.38033626138, 6462.4183823456115, 6462.545927166444, 6464.385883341075, 6470.352964703586, 6483.870322593521, 1680.9411670591471, 1702.7105392083377, 1703.931842726302, 1705.5650809043861, 1706.206613924531, 1064.0583083970741, 1065.9147268218524, 1084.2572837606265, 1086.6359769477156, 1174.618249069056, 6139.324674285825, 6235.96882015587, 6254.937450625526, 6255.530835187379, 6263.592866463665, 6542.54202205841, 6638.137042184485, 6650.301718319603, 6652.600843949379, 6664.966675166593, 6227.1282020517165, 6246.656433896089, 6249.6562689052535, 6256.812295631138, 6266.185395898504, 3574.0, 3611.4582812578324, 3612.692921101683, 3613.385724426854, 3613.638636136365, 6838.93161068383, 6852.417544508673, 6852.6916288767125, 6858.725650974005, 6858.828529286808, 6576.0, 6714.764983225564, 6722.0, 6723.22682891464, 6728.764493242713, 6828.419584335387, 6835.0, 6842.4868134890085, 6845.281245469254, 6848.554843935129, 6496.090547323473, 6628.966855165693, 6648.235452922976, 6656.600603963778, 6660.966695166687, 6644.235912870082, 6669.566478178903, 6671.199456065303, 6671.232808227116, 6678.332166783282, 6967.059446974594, 6969.6515174242095, 6970.65146742671, 6970.686319115551, 6980.092587963532, 4453.710508816916, 4556.134334476439, 4568.862934111982, 4616.746078965689, 4624.190766615761, 6723.361280678328, 6727.192736871607, 6731.158605174353, 6733.158355205599, 6733.831654208684, 6531.542792004514, 6533.542652014314, 6543.967280163569, 6545.0, 6549.115869357575, 6445.519192158979, 6478.190226221723, 6526.477881769352, 6527.608343499405, 6536.8365790855605, 6403.135576697273, 6406.679666016774, 6409.294977552372, 6411.51913606472, 6411.71147298382, 6550.508711846535, 6564.671766411565, 6566.540342176002, 6566.80299591013, 6573.1454910861285, 6280.591761535486, 6418.935810641834, 6458.257300410513, 6463.289038205699, 6464.0], "unit": "ms", "name": "prototype.html", "value": 4902.728482696979}, {"lowerIsBetter": false, "replicates": [819.8153667949813, 832.637802555888, 841.941064125511, 901.3014913442086, 908.5684299957521, 14340.4980825671, 14636.0, 14663.926680366583, 14664.926675366622, 14678.92660536697, 13257.668558286048, 13310.201387916732, 13338.0, 13357.53248636298, 13379.531716389929, 754.35125791819, 757.8749506331476, 905.6649039855263, 918.6646873891049, 926.8470702334107, 926.0507979321216, 935.4995077633467, 937.4890684576922, 944.2917811641269, 945.3004776465435, 960.0687333286701, 966.9081437263449, 968.7287559483356, 969.0696930946265, 969.9466529340883, 787.3307688464788, 792.5918152151618, 958.856171574266, 968.6077138758814, 969.2246203037541, 712.5653351455608, 717.7416130193126, 720.3732752505301, 721.2499001038914, 722.0180554445949, 13667.521636742666, 13789.241591712453, 13834.654133646638, 13834.723305533833, 13846.100003499841, 9828.606855725762, 11070.944645276722, 11911.166218364719, 12130.636080917498, 12152.878471215263, 10579.629712960086, 10705.732356691145, 10706.946465267702, 10737.570497180104, 10937.56249750009, 3811.00913762421, 3837.347650899354, 3853.441251018615, 3859.614038596146, 3859.710521710883, 47680.52319476796, 48258.51741482575, 48311.75844120757, 48880.26679599809, 48966.0, 10087.293889427816, 10679.83980240297, 10692.572297108185, 10754.677359679066, 10879.347239165676, 18548.165332560067, 19118.139683714267, 19162.0, 19163.904180479007, 19187.520311992314, 7769.961150194213, 7848.960755196187, 7937.801554961172, 7986.480878742863, 8014.879776803353, 4515.706479078849, 4562.8631141065825, 4575.153596584642, 4718.787654555554, 4724.692894961817, 3215.758818088653, 3230.402375560529, 3237.045071703853, 3237.1583388319204, 3242.3839470501002, 571.8541771848152, 580.1732531143105, 580.4021857486796, 580.8373655376502, 604.1964187630441, 676.509530590318, 683.2143035509105, 684.6302996381899, 692.9480288978347, 717.0176857704978, 8915.821683566292, 9331.860022099674, 9333.813323733488, 9358.391704539183, 9400.952995235117, 662.8641128568631, 685.6092027544352, 686.4199751210219, 733.5378711411777, 738.5568658804782, 4237.37672927482, 4298.226319262562, 4378.7372757634, 4477.955220447819, 4479.910401792011, 61163.38836611666, 62604.0, 62713.0, 62830.68584657047, 62896.371036289966, 55887.44112558904, 55963.16055259174, 56087.87824243492, 56814.715926420104, 57072.14391784126, 50211.0, 50444.24333634999, 50911.49088509067, 51051.48948510542, 51190.0, 8692.739217823475, 8776.122387761172, 8936.151065648754, 8940.955295223483, 8976.640934362553, 35530.11174720549, 35709.46435803517, 36168.0, 36193.45709814408, 36460.0, 46357.0, 49119.26321105115, 49506.75246623744, 49598.75200623974, 49867.50397488081, 11445.370504622326, 11691.240069395462, 12036.819447708467, 12108.091893107876, 12191.146619736533, 12877.162984405984, 13089.607311780663, 13510.594682159552, 13695.931520342334, 14218.217998010208, 12503.937480312541, 12512.499500019898, 12581.182223155465, 12638.66917390487, 13142.080054396483, 12549.372531373578, 12635.684107897376, 12750.744985100431, 13003.934980325415, 13257.07200495956, 11718.589849355232, 11861.644150675493, 12015.699607509881, 12473.5010599575, 12726.554570589984, 9642.325037247607, 9725.221982241545, 10048.899511004796, 10064.597416103274, 10274.948625257124, 9784.168345690412, 9902.257330700082, 9967.850482242628, 10192.592296308065, 10212.591496340361], "unit": "ms", "name": "yui.html", "value": 6728.486778721144}], "name": "dromaeo_css", "value": 7409.104257196285}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 102.0, 108.0, 112.0, 109.0, 99.0, 107.0, 108.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [157.0, 123.0, 153.0, 158.0, 124.0, 144.0, 147.0, 122.0, 158.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.9}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 162.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 71.0, 149.0, 72.0, 71.0, 72.0, 73.0, 147.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 87.2}, {"lowerIsBetter": true, "replicates": [91.0, 91.0, 134.0, 91.0, 92.0, 93.0, 130.0, 91.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 103.8}, {"lowerIsBetter": true, "replicates": [110.0, 112.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 147.0, 146.0, 147.0, 146.0, 146.0, 144.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 58.0, 58.0, 59.0, 58.0, 58.0, 59.0, 55.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.5}, {"lowerIsBetter": true, "replicates": [78.0, 78.0, 78.0, 78.0, 78.0, 77.0, 78.0, 80.0, 79.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.2}, {"lowerIsBetter": true, "replicates": [111.0, 112.0, 112.0, 121.0, 118.0, 118.0, 116.0, 110.0, 116.0, 137.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.1}, {"lowerIsBetter": true, "replicates": [132.0, 141.0, 140.0, 140.0, 144.0, 131.0, 141.0, 146.0, 141.0, 133.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.9}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 63.0, 64.0, 63.0, 63.0, 63.0, 64.0, 63.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.4}], "name": "kraken", "value": 1469.5000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.9261125104954], "unit": "score", "name": "Richards", "value": 210.22340999999997}, {"lowerIsBetter": false, "replicates": [168.49199663016006], "unit": "score", "name": "DeltaBlue", "value": 392.41032999999993}, {"lowerIsBetter": false, "replicates": [277.77777777777777], "unit": "score", "name": "Encrypt", "value": 958.2516000000002}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [944.2870632672333], "unit": "score", "name": "RayTrace", "value": 783.6483509999999}, {"lowerIsBetter": false, "replicates": [547.645125958379], "unit": "score", "name": "Earley", "value": 1216.961438}, {"lowerIsBetter": false, "replicates": [10536.842105263158], "unit": "score", "name": "Boyer", "value": 63.25073426573423}, {"lowerIsBetter": false, "replicates": [26315.78947368421], "unit": "score", "name": "RegExp", "value": 34.61742999999998}, {"lowerIsBetter": false, "replicates": [511.5089514066496], "unit": "score", "name": "Splay", "value": 159.31490499999992}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23096.360356494584}]} 06:17:06 INFO - RETURN: dromaeo_css: 7409.10 06:17:06 INFO - RETURN: kraken: 1469.50 06:17:06 INFO - RETURN: v8_7: 23096.36 06:17:06 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23096.36"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1469.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7409.10"}}} 06:17:06 INFO - Return code: 0 06:17:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:17:07 INFO - # TBPL SUCCESS # 06:17:07 INFO - Running post-action listener: _resource_record_post_action 06:17:07 INFO - Running post-run listener: _resource_record_post_run 06:17:08 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 323584; Write bytes: 519708672; Read time: 372; Write time: 576652 06:17:08 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 254644224; Read time: 228; Write time: 447416 06:17:08 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 319488; Write bytes: 265064448; Read time: 144; Write time: 129236 06:17:08 INFO - Running post-run listener: _upload_blobber_files 06:17:08 INFO - Blob upload gear active. 06:17:08 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:17:08 INFO - Copying logs to upload dir... 06:17:08 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.599444 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-20 06:17:07.317189) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-20 06:17:07.322703) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009556 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455967101/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-20 06:17:07.378843) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 06:17:07.379234) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1455967262.171493-767648864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-20 06:17:07.432101) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-20 06:17:07.432566) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-20 06:17:07.432890) ========= ========= Total master_lag: 0.28 =========