builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1451382569.08 results: success (0) buildid: 20151228232639 builduid: 3744996177134872af231cb38c649979 revision: 44eb237f155a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.077395) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.077849) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.078154) ========= 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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006443 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.118540) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.118858) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.181519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.181819) ========= 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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004723 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.232910) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.233499) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.234139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.234610) ========= 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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-29 01:49:30-- 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% 64.2M=0s 2015-12-29 01:49:30 (64.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.178238 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.427064) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.427445) ========= 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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020901 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.478076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.478476) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 44eb237f155a --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 44eb237f155a --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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-29 01:49:30,604 Setting DEBUG logging. 2015-12-29 01:49:30,604 attempt 1/10 2015-12-29 01:49:30,604 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/44eb237f155a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-29 01:49:30,844 unpacking tar archive at: mozilla-beta-44eb237f155a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.494256 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.986902) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.987223) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.997639) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.997894) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-29 01:49:29.998340) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-12-29 01:49:29.998636) ========= /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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:49:31 INFO - MultiFileLogger online at 20151229 01:49:31 in /builds/slave/test-pgo 01:49:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:49:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 01:49:31 INFO - {'append_to_log': False, 01:49:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 01:49:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:49:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 01:49:31 INFO - 'branch': 'Mozilla-Beta', 01:49:31 INFO - 'buildbot_json_path': 'buildprops.json', 01:49:31 INFO - 'config_files': ('talos/linux_config.py',), 01:49:31 INFO - 'default_actions': ('clobber', 01:49:31 INFO - 'read-buildbot-config', 01:49:31 INFO - 'download-and-extract', 01:49:31 INFO - 'populate-webroot', 01:49:31 INFO - 'create-virtualenv', 01:49:31 INFO - 'install', 01:49:31 INFO - 'run-tests'), 01:49:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:49:31 INFO - 'download_minidump_stackwalk': True, 01:49:31 INFO - 'download_symbols': 'ondemand', 01:49:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:49:31 INFO - 'tooltool.py': '/tools/tooltool.py', 01:49:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:49:31 INFO - '/tools/misc-python/virtualenv.py')}, 01:49:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:49:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:49:31 INFO - 'installer_path': 'installer.exe', 01:49:31 INFO - 'log_level': 'info', 01:49:31 INFO - 'log_name': 'talos', 01:49:31 INFO - 'log_to_console': True, 01:49:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:49:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:49:31 INFO - 'opt_config_files': (), 01:49:31 INFO - 'pip_index': False, 01:49:31 INFO - 'sps_profile': False, 01:49:31 INFO - 'sps_profile_interval': 0, 01:49:31 INFO - 'suite': 'dromaeojs', 01:49:31 INFO - 'system_bits': '32', 01:49:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:49:31 INFO - 'title': 'talos-linux64-ix-003', 01:49:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:49:31 INFO - 'use_talos_json': True, 01:49:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 01:49:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:49:31 INFO - 'work_dir': 'build'} 01:49:31 INFO - ##### 01:49:31 INFO - ##### Running clobber step. 01:49:31 INFO - ##### 01:49:31 INFO - Running pre-action listener: _resource_record_pre_action 01:49:31 INFO - Running main action method: clobber 01:49:31 INFO - rmtree: /builds/slave/test-pgo/build 01:49:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 01:49:31 INFO - Running post-action listener: _resource_record_post_action 01:49:31 INFO - ##### 01:49:31 INFO - ##### Running read-buildbot-config step. 01:49:31 INFO - ##### 01:49:31 INFO - Running pre-action listener: _resource_record_pre_action 01:49:31 INFO - Running main action method: read_buildbot_config 01:49:31 INFO - Using buildbot properties: 01:49:31 INFO - { 01:49:31 INFO - "properties": { 01:49:31 INFO - "buildnumber": 51, 01:49:31 INFO - "product": "firefox", 01:49:31 INFO - "script_repo_revision": "production", 01:49:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:49:31 INFO - "repository": "", 01:49:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 01:49:31 INFO - "buildid": "20151228232639", 01:49:31 INFO - "slavename": "talos-linux64-ix-003", 01:49:31 INFO - "pgo_build": "True", 01:49:31 INFO - "basedir": "/builds/slave/test-pgo", 01:49:31 INFO - "project": "", 01:49:31 INFO - "platform": "ubuntu64_hw", 01:49:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:49:31 INFO - "slavebuilddir": "test", 01:49:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:49:31 INFO - "branch": "mozilla-beta", 01:49:31 INFO - "repo_path": "releases/mozilla-beta", 01:49:31 INFO - "stage_platform": "linux64-pgo", 01:49:31 INFO - "builduid": "3744996177134872af231cb38c649979", 01:49:31 INFO - "revision": "44eb237f155a" 01:49:31 INFO - }, 01:49:31 INFO - "sourcestamp": { 01:49:31 INFO - "repository": "", 01:49:31 INFO - "hasPatch": false, 01:49:31 INFO - "project": "", 01:49:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:49:31 INFO - "changes": [ 01:49:31 INFO - { 01:49:31 INFO - "category": null, 01:49:31 INFO - "files": [ 01:49:31 INFO - { 01:49:31 INFO - "url": null, 01:49:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2" 01:49:31 INFO - } 01:49:31 INFO - ], 01:49:31 INFO - "repository": "", 01:49:31 INFO - "rev": "44eb237f155a", 01:49:31 INFO - "who": "sendchange", 01:49:31 INFO - "when": 1451382530, 01:49:31 INFO - "number": 7398760, 01:49:31 INFO - "comments": "Bug 1234553: Always request 16 bits audio for libavcodec 53. r=kentuckyfriedtakahe a=ritu", 01:49:31 INFO - "project": "", 01:49:31 INFO - "at": "Tue 29 Dec 2015 01:48:50", 01:49:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:49:31 INFO - "revlink": "", 01:49:31 INFO - "properties": [ 01:49:31 INFO - [ 01:49:31 INFO - "buildid", 01:49:31 INFO - "20151228232639", 01:49:31 INFO - "Change" 01:49:31 INFO - ], 01:49:31 INFO - [ 01:49:31 INFO - "builduid", 01:49:31 INFO - "3744996177134872af231cb38c649979", 01:49:31 INFO - "Change" 01:49:31 INFO - ], 01:49:31 INFO - [ 01:49:31 INFO - "pgo_build", 01:49:31 INFO - "True", 01:49:31 INFO - "Change" 01:49:31 INFO - ] 01:49:31 INFO - ], 01:49:31 INFO - "revision": "44eb237f155a" 01:49:31 INFO - } 01:49:31 INFO - ], 01:49:31 INFO - "revision": "44eb237f155a" 01:49:31 INFO - } 01:49:31 INFO - } 01:49:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2. 01:49:31 INFO - Running post-action listener: _resource_record_post_action 01:49:31 INFO - ##### 01:49:31 INFO - ##### Running download-and-extract step. 01:49:31 INFO - ##### 01:49:31 INFO - Running pre-action listener: _resource_record_pre_action 01:49:31 INFO - Running main action method: download_and_extract 01:49:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 01:49: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')]} 01:49:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/test_packages.json 01:49:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 01:49:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 01:49:31 INFO - Downloaded 1270 bytes. 01:49:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 01:49:31 INFO - Using the following test package requirements: 01:49:31 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 01:49:31 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 01:49:31 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:49:31 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 01:49:31 INFO - u'jsshell-linux-x86_64.zip'], 01:49:31 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 01:49:31 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:49:31 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 01:49:31 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 01:49:31 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 01:49:31 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 01:49:31 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 01:49:31 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 01:49:31 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:49:31 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 01:49:31 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 01:49:31 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:49:31 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:49: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')]} 01:49:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.common.tests.zip 01:49:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 01:49:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:49:37 INFO - Downloaded 22572191 bytes. 01:49:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:49:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 01:49:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:49:38 INFO - Return code: 0 01:49:38 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:49: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')]} 01:49:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.common.tests.zip 01:49:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 01:49:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:49:39 INFO - Downloaded 22572191 bytes. 01:49:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:49:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 01:49:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:49:40 INFO - Return code: 0 01:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 01:49:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 01:49:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:49:43 INFO - Downloaded 11131970 bytes. 01:49:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 01:49:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 01:49:43 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 01:49:43 INFO - Return code: 0 01:49:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2 01:49:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 01:49:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:49:45 INFO - Downloaded 56623557 bytes. 01:49:45 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2 01:49:45 INFO - mkdir: /builds/slave/test-pgo/properties 01:49:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 01:49:45 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 01:49:45 INFO - Contents: 01:49:45 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2 01:49:45 INFO - Running post-action listener: _resource_record_post_action 01:49:45 INFO - Running post-action listener: set_extra_try_arguments 01:49:45 INFO - ##### 01:49:45 INFO - ##### Running populate-webroot step. 01:49:45 INFO - ##### 01:49:45 INFO - Running pre-action listener: _resource_record_pre_action 01:49:45 INFO - Running main action method: populate_webroot 01:49:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:49:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:49:45 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 01:49:45 INFO - '../fennec_ids.txt'], 01:49:45 INFO - 'tests': ['tcheck2']}, 01:49:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:49:45 INFO - '--tppagecycles', 01:49:45 INFO - '7'], 01:49:45 INFO - 'tests': ['tsvgm']}}, 01:49:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:49:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:49:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:49:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:49:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:49:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:49:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'tests': ['damp', 'tps']}, 01:49:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['damp', 'tps']}, 01:49:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:49:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:49:45 INFO - 'other': {'tests': ['a11yr', 01:49:45 INFO - 'ts_paint', 01:49:45 INFO - 'tpaint', 01:49:45 INFO - 'sessionrestore', 01:49:45 INFO - 'sessionrestore_no_auto_restore']}, 01:49:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['a11yr', 01:49:45 INFO - 'ts_paint', 01:49:45 INFO - 'tpaint', 01:49:45 INFO - 'sessionrestore', 01:49:45 INFO - 'sessionrestore_no_auto_restore']}, 01:49:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['a11yr', 01:49:45 INFO - 'ts_paint', 01:49:45 INFO - 'tpaint', 01:49:45 INFO - 'sessionrestore', 01:49:45 INFO - 'sessionrestore_no_auto_restore']}, 01:49:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['a11yr', 01:49:45 INFO - 'ts_paint', 01:49:45 INFO - 'tpaint', 01:49:45 INFO - 'sessionrestore', 01:49:45 INFO - 'sessionrestore_no_auto_restore']}, 01:49:45 INFO - 'other_l64': {'tests': ['a11yr', 01:49:45 INFO - 'ts_paint', 01:49:45 INFO - 'tpaint', 01:49:45 INFO - 'sessionrestore', 01:49:45 INFO - 'sessionrestore_no_auto_restore']}, 01:49:45 INFO - 'other_nol64': {'tests': ['a11yr', 01:49:45 INFO - 'ts_paint', 01:49:45 INFO - 'tpaint', 01:49:45 INFO - 'sessionrestore', 01:49:45 INFO - 'sessionrestore_no_auto_restore']}, 01:49:45 INFO - 'svgr': {'tests': ['tsvgx', 01:49:45 INFO - 'tsvgr_opacity', 01:49:45 INFO - 'tart', 01:49:45 INFO - 'tscrollx', 01:49:45 INFO - 'cart']}, 01:49:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['tsvgx', 01:49:45 INFO - 'tsvgr_opacity', 01:49:45 INFO - 'tart', 01:49:45 INFO - 'tscrollx', 01:49:45 INFO - 'cart']}, 01:49:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'tests': ['tp5o']}, 01:49:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'talos_options': ['--e10s'], 01:49:45 INFO - 'tests': ['tp5o']}, 01:49:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'talos_options': ['--xperf_path', 01:49:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:49:45 INFO - 'tests': ['tp5n']}, 01:49:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:49:45 INFO - 'talos_options': ['--e10s', 01:49:45 INFO - '--xperf_path', 01:49:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:49:45 INFO - 'tests': ['tp5n']}}, 01:49:45 INFO - 'talos.zip': {'path': '', 01:49:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:49:45 INFO - Running post-action listener: _resource_record_post_action 01:49:45 INFO - ##### 01:49:45 INFO - ##### Running create-virtualenv step. 01:49:45 INFO - ##### 01:49:45 INFO - Running pre-action listener: _resource_record_pre_action 01:49:45 INFO - Running main action method: create_virtualenv 01:49:45 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 01:49:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 01:49:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 01:49:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:49:45 INFO - Using real prefix '/usr' 01:49:45 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 01:49:45 INFO - Installing distribute.............................................................................................................................................................................................done. 01:49:47 INFO - Installing pip.................done. 01:49:47 INFO - Return code: 0 01:49:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 01:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:49:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 01:49:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:49:47 INFO - Using env: {'DISPLAY': ':0', 01:49:47 INFO - 'HOME': '/home/cltbld', 01:49:47 INFO - 'LANG': 'en_US.UTF-8', 01:49:47 INFO - 'LANGUAGE': 'en_US:en', 01:49:47 INFO - 'LOGNAME': 'cltbld', 01:49:47 INFO - 'MAIL': '/var/mail/cltbld', 01:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:47 INFO - 'MOZ_NO_REMOTE': '1', 01:49:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:47 INFO - 'NO_EM_RESTART': '1', 01:49:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:49:47 INFO - 'PWD': '/builds/slave/test-pgo', 01:49:47 INFO - 'SHELL': '/bin/bash', 01:49:47 INFO - 'SHLVL': '1', 01:49:47 INFO - 'TERM': 'linux', 01:49:47 INFO - 'TMOUT': '86400', 01:49:47 INFO - 'USER': 'cltbld', 01:49:47 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:47 INFO - '_': '/tools/buildbot/bin/python'} 01:49:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:49:47 INFO - Cleaning up... 01:49:47 INFO - Return code: 0 01:49:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 01:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:49:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 01:49:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:49:47 INFO - Using env: {'DISPLAY': ':0', 01:49:47 INFO - 'HOME': '/home/cltbld', 01:49:47 INFO - 'LANG': 'en_US.UTF-8', 01:49:47 INFO - 'LANGUAGE': 'en_US:en', 01:49:47 INFO - 'LOGNAME': 'cltbld', 01:49:47 INFO - 'MAIL': '/var/mail/cltbld', 01:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:47 INFO - 'MOZ_NO_REMOTE': '1', 01:49:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:47 INFO - 'NO_EM_RESTART': '1', 01:49:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:49:47 INFO - 'PWD': '/builds/slave/test-pgo', 01:49:47 INFO - 'SHELL': '/bin/bash', 01:49:47 INFO - 'SHLVL': '1', 01:49:47 INFO - 'TERM': 'linux', 01:49:47 INFO - 'TMOUT': '86400', 01:49:47 INFO - 'USER': 'cltbld', 01:49:47 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:47 INFO - '_': '/tools/buildbot/bin/python'} 01:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:48 INFO - Downloading/unpacking psutil>=0.7.1 01:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:49 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 01:49:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 01:49:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 01:49:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:49:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:49:50 INFO - Installing collected packages: psutil 01:49:50 INFO - Running setup.py install for psutil 01:49:50 INFO - building 'psutil._psutil_linux' extension 01:49:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:49:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:49:50 INFO - building 'psutil._psutil_posix' extension 01:49:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:49:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:49:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:49:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:49:50 INFO - Successfully installed psutil 01:49:50 INFO - Cleaning up... 01:49:50 INFO - Return code: 0 01:49:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 01:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:49:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 01:49:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 01:49:50 INFO - Using env: {'DISPLAY': ':0', 01:49:50 INFO - 'HOME': '/home/cltbld', 01:49:50 INFO - 'LANG': 'en_US.UTF-8', 01:49:50 INFO - 'LANGUAGE': 'en_US:en', 01:49:50 INFO - 'LOGNAME': 'cltbld', 01:49:50 INFO - 'MAIL': '/var/mail/cltbld', 01:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:50 INFO - 'MOZ_NO_REMOTE': '1', 01:49:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:50 INFO - 'NO_EM_RESTART': '1', 01:49:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:49:50 INFO - 'PWD': '/builds/slave/test-pgo', 01:49:50 INFO - 'SHELL': '/bin/bash', 01:49:50 INFO - 'SHLVL': '1', 01:49:50 INFO - 'TERM': 'linux', 01:49:50 INFO - 'TMOUT': '86400', 01:49:50 INFO - 'USER': 'cltbld', 01:49:50 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:50 INFO - '_': '/tools/buildbot/bin/python'} 01:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:49:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 01:49:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:49:52 INFO - Installing collected packages: mozsystemmonitor 01:49:52 INFO - Running setup.py install for mozsystemmonitor 01:49:52 INFO - Successfully installed mozsystemmonitor 01:49:52 INFO - Cleaning up... 01:49:52 INFO - Return code: 0 01:49:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 01:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:49:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 01:49:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:49:52 INFO - Using env: {'DISPLAY': ':0', 01:49:52 INFO - 'HOME': '/home/cltbld', 01:49:52 INFO - 'LANG': 'en_US.UTF-8', 01:49:52 INFO - 'LANGUAGE': 'en_US:en', 01:49:52 INFO - 'LOGNAME': 'cltbld', 01:49:52 INFO - 'MAIL': '/var/mail/cltbld', 01:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:52 INFO - 'MOZ_NO_REMOTE': '1', 01:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:52 INFO - 'NO_EM_RESTART': '1', 01:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:49:52 INFO - 'PWD': '/builds/slave/test-pgo', 01:49:52 INFO - 'SHELL': '/bin/bash', 01:49:52 INFO - 'SHLVL': '1', 01:49:52 INFO - 'TERM': 'linux', 01:49:52 INFO - 'TMOUT': '86400', 01:49:52 INFO - 'USER': 'cltbld', 01:49:52 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:52 INFO - '_': '/tools/buildbot/bin/python'} 01:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:52 INFO - Downloading/unpacking blobuploader==1.2.4 01:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:54 INFO - Downloading blobuploader-1.2.4.tar.gz 01:49:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 01:49:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:49:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 01:49:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 01:49:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:49:55 INFO - Downloading docopt-0.6.1.tar.gz 01:49:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 01:49:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 01:49:55 INFO - Installing collected packages: blobuploader, requests, docopt 01:49:55 INFO - Running setup.py install for blobuploader 01:49:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:49:55 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 01:49:55 INFO - Running setup.py install for requests 01:49:55 INFO - Running setup.py install for docopt 01:49:55 INFO - Successfully installed blobuploader requests docopt 01:49:55 INFO - Cleaning up... 01:49:55 INFO - Return code: 0 01:49:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:49:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 01:49:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:49:55 INFO - Using env: {'DISPLAY': ':0', 01:49:55 INFO - 'HOME': '/home/cltbld', 01:49:55 INFO - 'LANG': 'en_US.UTF-8', 01:49:55 INFO - 'LANGUAGE': 'en_US:en', 01:49:55 INFO - 'LOGNAME': 'cltbld', 01:49:55 INFO - 'MAIL': '/var/mail/cltbld', 01:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:55 INFO - 'MOZ_NO_REMOTE': '1', 01:49:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:55 INFO - 'NO_EM_RESTART': '1', 01:49:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:49:55 INFO - 'PWD': '/builds/slave/test-pgo', 01:49:55 INFO - 'SHELL': '/bin/bash', 01:49:55 INFO - 'SHLVL': '1', 01:49:55 INFO - 'TERM': 'linux', 01:49:55 INFO - 'TMOUT': '86400', 01:49:55 INFO - 'USER': 'cltbld', 01:49:55 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:55 INFO - '_': '/tools/buildbot/bin/python'} 01:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:49:56 INFO - Running setup.py (path:/tmp/pip-fcsnaa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:49:56 INFO - Running setup.py (path:/tmp/pip-pKRmzn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:49:56 INFO - Running setup.py (path:/tmp/pip-tRVBc3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:49:56 INFO - Running setup.py (path:/tmp/pip-bLXz6s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:49:56 INFO - Running setup.py (path:/tmp/pip-6Xmh6k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:49:56 INFO - Running setup.py (path:/tmp/pip-s4mzKQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:49:56 INFO - Running setup.py (path:/tmp/pip-hREIUk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:49:56 INFO - Running setup.py (path:/tmp/pip-a6o7iT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:49:56 INFO - Running setup.py (path:/tmp/pip-lSfuDP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:49:56 INFO - Running setup.py (path:/tmp/pip-R_9I0p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:49:56 INFO - Running setup.py (path:/tmp/pip-g2Kdt1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:49:56 INFO - Running setup.py (path:/tmp/pip-HTl5gL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:49:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:49:56 INFO - Running setup.py (path:/tmp/pip-2Q8IpJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:49:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:49:57 INFO - Running setup.py (path:/tmp/pip-hY2qdv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:49:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:49:57 INFO - Running setup.py (path:/tmp/pip-Mu_gv5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:49:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:49:57 INFO - Running setup.py (path:/tmp/pip-oRQwer-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:49:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:49:57 INFO - Running setup.py (path:/tmp/pip-3JfaAq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:49:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:49:57 INFO - Running setup.py install for manifestparser 01:49:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 01:49:57 INFO - Running setup.py install for mozcrash 01:49:57 INFO - Running setup.py install for mozdebug 01:49:57 INFO - Running setup.py install for mozdevice 01:49:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 01:49:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 01:49:57 INFO - Running setup.py install for mozfile 01:49:57 INFO - Running setup.py install for mozhttpd 01:49:57 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 01:49:57 INFO - Running setup.py install for mozinfo 01:49:58 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Running setup.py install for mozInstall 01:49:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Running setup.py install for mozleak 01:49:58 INFO - Running setup.py install for mozlog 01:49:58 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Running setup.py install for moznetwork 01:49:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Running setup.py install for mozprocess 01:49:58 INFO - Running setup.py install for mozprofile 01:49:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Running setup.py install for mozrunner 01:49:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 01:49:58 INFO - Running setup.py install for mozscreenshot 01:49:58 INFO - Running setup.py install for moztest 01:49:59 INFO - Running setup.py install for mozversion 01:49:59 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 01:49:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:49:59 INFO - Cleaning up... 01:49:59 INFO - Return code: 0 01:49:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:49: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')]} 01:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49: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')]} 01:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:49:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:49:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 01:49:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:49:59 INFO - Using env: {'DISPLAY': ':0', 01:49:59 INFO - 'HOME': '/home/cltbld', 01:49:59 INFO - 'LANG': 'en_US.UTF-8', 01:49:59 INFO - 'LANGUAGE': 'en_US:en', 01:49:59 INFO - 'LOGNAME': 'cltbld', 01:49:59 INFO - 'MAIL': '/var/mail/cltbld', 01:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:59 INFO - 'MOZ_NO_REMOTE': '1', 01:49:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:49:59 INFO - 'NO_EM_RESTART': '1', 01:49:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:49:59 INFO - 'PWD': '/builds/slave/test-pgo', 01:49:59 INFO - 'SHELL': '/bin/bash', 01:49:59 INFO - 'SHLVL': '1', 01:49:59 INFO - 'TERM': 'linux', 01:49:59 INFO - 'TMOUT': '86400', 01:49:59 INFO - 'USER': 'cltbld', 01:49:59 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:59 INFO - '_': '/tools/buildbot/bin/python'} 01:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:49:59 INFO - Running setup.py (path:/tmp/pip-f3va5i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:49:59 INFO - Running setup.py (path:/tmp/pip-2nIXbp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:49:59 INFO - Running setup.py (path:/tmp/pip-BEhUkI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:49:59 INFO - Running setup.py (path:/tmp/pip-WC2nej-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:49:59 INFO - Running setup.py (path:/tmp/pip-7IBTdG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:49:59 INFO - Running setup.py (path:/tmp/pip-rZZ8_w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:49:59 INFO - Running setup.py (path:/tmp/pip-TkWCUQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:49:59 INFO - Running setup.py (path:/tmp/pip-gZWQyg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:49:59 INFO - Running setup.py (path:/tmp/pip-BrRsZY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 01:49:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:49:59 INFO - Running setup.py (path:/tmp/pip-ArJIqD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:50:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:50:00 INFO - Running setup.py (path:/tmp/pip-y8K7ff-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 01:50:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:50:00 INFO - Running setup.py (path:/tmp/pip-gXGAK1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 01:50:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:50:00 INFO - Running setup.py (path:/tmp/pip-zjc5zs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 01:50:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:50:00 INFO - Running setup.py (path:/tmp/pip-DgXvym-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 01:50:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:50:00 INFO - Running setup.py (path:/tmp/pip-jWU131-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 01:50:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:50:00 INFO - Running setup.py (path:/tmp/pip-j3bSod-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 01:50:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:50:00 INFO - Running setup.py (path:/tmp/pip-xoFflI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 01:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 01:50:00 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 01:50:00 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 01:50:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:02 INFO - Downloading blessings-1.5.1.tar.gz 01:50:02 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 01:50:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 01:50:02 INFO - Installing collected packages: blessings 01:50:02 INFO - Running setup.py install for blessings 01:50:02 INFO - Successfully installed blessings 01:50:02 INFO - Cleaning up... 01:50:02 INFO - Return code: 0 01:50:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 01:50:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:50:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:50:02 INFO - Reading from file tmpfile_stdout 01:50:02 INFO - Current package versions: 01:50:02 INFO - argparse == 1.2.1 01:50:02 INFO - blessings == 1.5.1 01:50:02 INFO - blobuploader == 1.2.4 01:50:02 INFO - docopt == 0.6.1 01:50:02 INFO - manifestparser == 1.1 01:50:02 INFO - mozInstall == 1.12 01:50:02 INFO - mozcrash == 0.16 01:50:02 INFO - mozdebug == 0.1 01:50:02 INFO - mozdevice == 0.46 01:50:02 INFO - mozfile == 1.2 01:50:02 INFO - mozhttpd == 0.7 01:50:02 INFO - mozinfo == 0.8 01:50:02 INFO - mozleak == 0.1 01:50:02 INFO - mozlog == 3.0 01:50:02 INFO - moznetwork == 0.27 01:50:02 INFO - mozprocess == 0.22 01:50:02 INFO - mozprofile == 0.27 01:50:02 INFO - mozrunner == 6.11 01:50:02 INFO - mozscreenshot == 0.1 01:50:02 INFO - mozsystemmonitor == 0.0 01:50:02 INFO - moztest == 0.7 01:50:02 INFO - mozversion == 1.4 01:50:02 INFO - psutil == 3.1.1 01:50:02 INFO - requests == 1.2.3 01:50:02 INFO - wsgiref == 0.1.2 01:50:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:50:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 01:50:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:50:02 INFO - Using env: {'DISPLAY': ':0', 01:50:02 INFO - 'HOME': '/home/cltbld', 01:50:02 INFO - 'LANG': 'en_US.UTF-8', 01:50:02 INFO - 'LANGUAGE': 'en_US:en', 01:50:02 INFO - 'LOGNAME': 'cltbld', 01:50:02 INFO - 'MAIL': '/var/mail/cltbld', 01:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:02 INFO - 'MOZ_NO_REMOTE': '1', 01:50:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:02 INFO - 'NO_EM_RESTART': '1', 01:50:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:50:02 INFO - 'PWD': '/builds/slave/test-pgo', 01:50:02 INFO - 'SHELL': '/bin/bash', 01:50:02 INFO - 'SHLVL': '1', 01:50:02 INFO - 'TERM': 'linux', 01:50:02 INFO - 'TMOUT': '86400', 01:50:02 INFO - 'USER': 'cltbld', 01:50:02 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:02 INFO - '_': '/tools/buildbot/bin/python'} 01:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 01:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:50:02 INFO - Cleaning up... 01:50:02 INFO - Return code: 0 01:50:02 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 01:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:50:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 01:50:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:50:02 INFO - Using env: {'DISPLAY': ':0', 01:50:02 INFO - 'HOME': '/home/cltbld', 01:50:02 INFO - 'LANG': 'en_US.UTF-8', 01:50:02 INFO - 'LANGUAGE': 'en_US:en', 01:50:02 INFO - 'LOGNAME': 'cltbld', 01:50:02 INFO - 'MAIL': '/var/mail/cltbld', 01:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:02 INFO - 'MOZ_NO_REMOTE': '1', 01:50:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:02 INFO - 'NO_EM_RESTART': '1', 01:50:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:50:02 INFO - 'PWD': '/builds/slave/test-pgo', 01:50:02 INFO - 'SHELL': '/bin/bash', 01:50:02 INFO - 'SHLVL': '1', 01:50:02 INFO - 'TERM': 'linux', 01:50:02 INFO - 'TMOUT': '86400', 01:50:02 INFO - 'USER': 'cltbld', 01:50:02 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:02 INFO - '_': '/tools/buildbot/bin/python'} 01:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:03 INFO - Downloading/unpacking jsonschema 01:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:50:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 01:50:04 INFO - Downloading/unpacking functools32 (from jsonschema) 01:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:05 INFO - Downloading functools32-3.2.3-2.tar.gz 01:50:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 01:50:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 01:50:05 INFO - warning: no files found matching '*.txt' 01:50:05 INFO - no previously-included directories found matching 'build' 01:50:05 INFO - no previously-included directories found matching 'dist' 01:50:05 INFO - no previously-included directories found matching '.git*' 01:50:05 INFO - Installing collected packages: jsonschema, functools32 01:50:05 INFO - Running setup.py install for functools32 01:50:05 INFO - warning: no files found matching '*.txt' 01:50:05 INFO - no previously-included directories found matching 'build' 01:50:05 INFO - no previously-included directories found matching 'dist' 01:50:05 INFO - no previously-included directories found matching '.git*' 01:50:05 INFO - Successfully installed jsonschema functools32 01:50:05 INFO - Cleaning up... 01:50:05 INFO - Return code: 0 01:50:05 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 01:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:50:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:50:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:50:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef79e9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12720e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, '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': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:50:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 01:50:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:50:05 INFO - Using env: {'DISPLAY': ':0', 01:50:05 INFO - 'HOME': '/home/cltbld', 01:50:05 INFO - 'LANG': 'en_US.UTF-8', 01:50:05 INFO - 'LANGUAGE': 'en_US:en', 01:50:05 INFO - 'LOGNAME': 'cltbld', 01:50:05 INFO - 'MAIL': '/var/mail/cltbld', 01:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:05 INFO - 'MOZ_NO_REMOTE': '1', 01:50:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:05 INFO - 'NO_EM_RESTART': '1', 01:50:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:50:05 INFO - 'PWD': '/builds/slave/test-pgo', 01:50:05 INFO - 'SHELL': '/bin/bash', 01:50:05 INFO - 'SHLVL': '1', 01:50:05 INFO - 'TERM': 'linux', 01:50:05 INFO - 'TMOUT': '86400', 01:50:05 INFO - 'USER': 'cltbld', 01:50:05 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:05 INFO - '_': '/tools/buildbot/bin/python'} 01:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:50:05 INFO - Downloading/unpacking flake8 01:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:50:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 01:50:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:50:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 01:50:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 01:50:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:50:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 01:50:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 01:50:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:50:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:50:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:50:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:50:07 INFO - no previously-included directories found matching 'docs/_build' 01:50:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:50:07 INFO - Running setup.py install for pep8 01:50:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:50:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:50:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:50:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:50:07 INFO - no previously-included directories found matching 'docs/_build' 01:50:08 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 01:50:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:50:08 INFO - Cleaning up... 01:50:08 INFO - Return code: 0 01:50:08 INFO - Running post-action listener: _resource_record_post_action 01:50:08 INFO - Running post-action listener: _start_resource_monitoring 01:50:08 INFO - Starting resource monitoring. 01:50:08 INFO - ##### 01:50:08 INFO - ##### Running install step. 01:50:08 INFO - ##### 01:50:08 INFO - Running pre-action listener: _resource_record_pre_action 01:50:08 INFO - Running main action method: install 01:50:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:50:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:50:08 INFO - Reading from file tmpfile_stdout 01:50:08 INFO - Detecting whether we're running mozinstall >=1.0... 01:50:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 01:50:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 01:50:08 INFO - Reading from file tmpfile_stdout 01:50:08 INFO - Output received: 01:50:08 INFO - Usage: mozinstall [options] installer 01:50:08 INFO - Options: 01:50:08 INFO - -h, --help show this help message and exit 01:50:08 INFO - -d DEST, --destination=DEST 01:50:08 INFO - Directory to install application into. [default: 01:50:08 INFO - "/builds/slave/test-pgo"] 01:50:08 INFO - --app=APP Application being installed. [default: firefox] 01:50:08 INFO - mkdir: /builds/slave/test-pgo/build/application 01:50:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 01:50:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 01:50:22 INFO - Reading from file tmpfile_stdout 01:50:22 INFO - Output received: 01:50:22 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 01:50:22 INFO - Running post-action listener: _resource_record_post_action 01:50:22 INFO - ##### 01:50:22 INFO - ##### Running run-tests step. 01:50:22 INFO - ##### 01:50:22 INFO - Running pre-action listener: _resource_record_pre_action 01:50:22 INFO - Running main action method: run_tests 01:50:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 01:50:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 01:50:22 INFO - Python 2.7.3 01:50:22 INFO - Return code: 0 01:50:22 INFO - grabbing minidump binary from tooltool 01:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:50:22 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 0x1300e30>, '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 0x1301d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13021c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 01:50:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 01:50:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:50:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:50:22 INFO - Return code: 0 01:50:22 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 01:50:22 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 01:50:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 01:50:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 01:50:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 01:50:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 01:50:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 01:50:23 INFO - Return code: 0 01:50:23 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/1451373999/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 01:50:23 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/1451373999/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost 01:50:23 INFO - Using env: {'DISPLAY': ':0', 01:50:23 INFO - 'HOME': '/home/cltbld', 01:50:23 INFO - 'LANG': 'en_US.UTF-8', 01:50:23 INFO - 'LANGUAGE': 'en_US:en', 01:50:23 INFO - 'LOGNAME': 'cltbld', 01:50:23 INFO - 'MAIL': '/var/mail/cltbld', 01:50:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:50:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 01:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:50:23 INFO - 'MOZ_NO_REMOTE': '1', 01:50:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:50:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:50:23 INFO - 'NO_EM_RESTART': '1', 01:50:23 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:50:23 INFO - 'PWD': '/builds/slave/test-pgo', 01:50:23 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 01:50:23 INFO - 'SHELL': '/bin/bash', 01:50:23 INFO - 'SHLVL': '1', 01:50:23 INFO - 'TERM': 'linux', 01:50:23 INFO - 'TMOUT': '86400', 01:50:23 INFO - 'USER': 'cltbld', 01:50:23 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451375669.21767-173998707', 01:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:50:23 INFO - '_': '/tools/buildbot/bin/python'} 01:50:23 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/1451373999/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] with output_timeout 3600 01:50:23 INFO - mozversion INFO | application_buildid: 20151228232639 01:50:23 INFO - mozversion INFO | application_changeset: 44eb237f155a936d851ce2998ce7c8e6eb047f16 01:50:23 INFO - mozversion INFO | application_display_name: Nightly 01:50:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:50:23 INFO - mozversion INFO | application_name: Firefox 01:50:23 INFO - mozversion INFO | application_remotingname: firefox 01:50:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:50:23 INFO - mozversion INFO | application_vendor: Mozilla 01:50:23 INFO - mozversion INFO | application_version: 44.0 01:50:23 INFO - mozversion INFO | platform_buildid: 20151228232639 01:50:23 INFO - mozversion INFO | platform_changeset: 44eb237f155a936d851ce2998ce7c8e6eb047f16 01:50:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:50:23 INFO - mozversion INFO | platform_version: 44.0 01:50:23 INFO - 2015-12-29 01:50:23,815 DEBUG : using testdate: 1451382623 01:50:23 INFO - 2015-12-29 01:50:23,815 DEBUG : actual date: 1451382623 01:50:23 INFO - 2015-12-29 01:50:23,824 INFO : starting webserver on 'localhost:33734' 01:50:23 INFO - 2015-12-29 01:50:23,825 INFO : Starting test suite talos-linux64-ix-003 01:50:23 INFO - 2015-12-29 01:50:23,825 INFO : Starting test dromaeo_css 01:50:23 INFO - 2015-12-29 01:50:23,826 DEBUG : operating with platform_type : linux_ 01:50:23 INFO - 2015-12-29 01:50:23,826 INFO : Initialising browser for dromaeo_css test... 01:50:23 INFO - 2015-12-29 01:50:23,842 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppyhAcL/profile http://localhost:33734/getInfo.html 01:50:27 INFO - 2015-12-29 01:50:27,301 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:50:27 INFO - 2015-12-29 01:50:27,301 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:50:27 INFO - 2015-12-29 01:50:27,301 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:50:27 INFO - 2015-12-29 01:50:27,301 DEBUG : BROWSER_OUTPUT: __metrics 01:50:27 INFO - 2015-12-29 01:50:27,519 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:50:28 INFO - 2015-12-29 01:50:28,533 INFO : Browser initialized. 01:50:28 INFO - 2015-12-29 01:50:28,534 INFO : Running cycle 1/1 for dromaeo_css test... 01:50:28 INFO - 2015-12-29 01:50:28,534 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppyhAcL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:50:40 INFO - 2015-12-29 01:50:40,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 128020480 01:50:40 INFO - 2015-12-29 01:50:40,071 DEBUG : BROWSER_OUTPUT: 01:51:30 INFO - 2015-12-29 01:51:30,964 DEBUG : BROWSER_OUTPUT: 1451382690960 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:53:38 INFO - 2015-12-29 01:53:38,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33734/tests/dromaeo/cssquery-ext.html) 01:53:39 INFO - 2015-12-29 01:53:39,949 DEBUG : BROWSER_OUTPUT: RSS: Main: 235638784 01:53:39 INFO - 2015-12-29 01:53:39,949 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2015-12-29 01:56:42,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/dromaeo/cssquery-ext.html (next: http://localhost:33734/tests/dromaeo/cssquery-jquery.html) 01:56:42 INFO - 2015-12-29 01:56:42,558 DEBUG : BROWSER_OUTPUT: RSS: Main: 203292672 01:56:42 INFO - 2015-12-29 01:56:42,558 DEBUG : BROWSER_OUTPUT: 01:59:44 INFO - 2015-12-29 01:59:44,225 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33734/tests/dromaeo/cssquery-mootools.html) 01:59:44 INFO - 2015-12-29 01:59:44,788 DEBUG : BROWSER_OUTPUT: RSS: Main: 209543168 01:59:44 INFO - 2015-12-29 01:59:44,788 DEBUG : BROWSER_OUTPUT: 02:02:46 INFO - 2015-12-29 02:02:46,448 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33734/tests/dromaeo/cssquery-prototype.html) 02:02:47 INFO - 2015-12-29 02:02:47,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 193830912 02:02:47 INFO - 2015-12-29 02:02:47,017 DEBUG : BROWSER_OUTPUT: 02:05:49 INFO - 2015-12-29 02:05:49,279 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33734/tests/dromaeo/cssquery-yui.html) 02:05:49 INFO - 2015-12-29 02:05:49,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 194727936 02:05:49 INFO - 2015-12-29 02:05:49,833 DEBUG : BROWSER_OUTPUT: 02:08:51 INFO - 2015-12-29 02:08:51,952 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/dromaeo/cssquery-yui.html (next: http://localhost:33734/tests/dromaeo/cssquery-dojo.html) 02:08:52 INFO - 2015-12-29 02:08:52,203 DEBUG : BROWSER_OUTPUT: RSS: Main: 238784512 02:08:52 INFO - 2015-12-29 02:08:52,203 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,203 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:08:52 INFO - 2015-12-29 02:08:52,203 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:08:52 INFO - 2015-12-29 02:08:52,205 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:08:52 INFO - 2015-12-29 02:08:52,205 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:08:52 INFO - 2015-12-29 02:08:52,206 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3309.2719601687622;3394.4738565522352;3405.0125463615555;3410.6759857813513;3439.8968030959063;5566.248556444879;5586.413426590206;5631.436856314366;5632.8591785205335;5639.971800141004;5627.099664053752;5643.943560564393;5724.828255152342;5735.512481439072;5748.741306641201;4953.727544985042;5150.948490515084;5178.300929374543;5257.605679574028;5283.6037297202865;4996.425411077718;5103.668261563005;5115.232715092748;5143.254228136918;5237.816676416326;5122.462141475129;5126.128558145124;5151.330327057477;5152.046871328797;5161.225816127592;4962.429320628138;5146.279520867081;5155.020546096267;5156.74216289184;5204.271402003723;5014.573761230264;5074.974625126888;5146.305248791403;5158.097332966716;5176.275321454962;5431.429699881534;5446.509814116709;5450.454954504517;5460.26286451328;5471.37079235885;5876.647401155901;6003.399660034005;6093.207882975229;6102.755889764404;6107.022876339809;6030.638161710268;6075.027995520695;6097.817065488042;6100.054491553817;6103.359147289515;5716.599838011382;5719.885602287973;5720.771169153229;5738.225339579187;5769;6010.549208809357;6013.0980352946535;6126.36500017443;6167.537434692326;6186.721597528122;5977.342492325841;6009.699515024231;6013.067974463965;6044.1840351552855;6064.878702425926;6098.207233059763;6114;6116.633002019893;6121;6121.051237058263;5798.82603521895;5900.734466948998;6009.789657362059;6088.4215999479975;6095.939040609625;6111.7500336055855;6115.756844843582;6123.601965872204;6140.293866205354;6165.290991535941;4656.231721765938;4664.39362882823;4669.182892993713;4691.554302341272;4692.249240121564;4026.5973402660084;4073.7759423232005;4076.2255171517318;4148.23257697327;4189.2668782962855;5778.855528611818;5841.474267315877;5843.065109582446;5845;5850.882982340415;6156.045812899008;6174.166487524127;6192.969035154885;6193.566450348522;6203.937960620336;5524.254225679482;5590.608657394025;5628.465295796893;5675.5459563234845;5688.914666280009;5310;5349.1976203569775;5349.411564727877;5352.598555108382;5353.491418315254;6152.969235153795;6369.936300637027;6375.904361434582;6378.585391949508;6380;6017.0974353846395;6047.606905551125;6161.722722477589;6167.537434692326;6168.228971378578;6301.590396624205;6309.4952403806665;6312.968435157794;6314.526410519321;6316.494680425463;5816.912746308724;5899.056151015817;6035.064564992434;6047.244094488189;6047.304559975659;6022.09668549721;6110.980918142951;6113.90744334296;6115.045260690715;6116.506790579029;5925.200097986716;6006.15913772081;6017.638941663514;6018.548608854266;6018.789342372996;5745.425457454221;5791.9131213031;5830.912536311874;5998.970005149947;6025.427584379477;5926.822195334147;5931.466168044896;5934.495567876779;5935.465808077293;5957.046872500379;5944.227250457586;6030.758769649165;6032.245969253843;6034.155218269358;6048.637081775108;5685.061964775931;5765.91351129725;5858.2677165354335;5916.7041647918295;5986.431289027535 02:08:52 INFO - 2015-12-29 02:08:52,206 DEBUG : BROWSER_OUTPUT: |1;ext.html;578.878435528539;611.541343992006;613.8434699151717;614.0022463496817;628.4249911331133;34925.47611785822;42615.78692106539;43073.7846310768;43129.56870431295;43133.7843310783;46440;46975.29537056947;47011.82470438232;47056.764716176374;47100.52899471004;8115.715949941753;8388.916110838874;9254.583543740515;9430.198433133191;10724.195685323593;10972.780544389107;11037.061849742782;11081.944590277079;11091.445427728622;11099.500522476468;11048.834267485994;11166.665000050012;11174.944125279362;11177.105831533498;11181.105511559055;10931.344119352825;11008.119350451945;11010.559577616887;11028.393438360887;11041;10720.78558428835;10819.67540973772;10847.837282440769;10848.457577121113;10854.34873907568;43054.56945430537;43487.130257394674;43607.34588981168;43628.03673834685;43645;3603.4594810778594;3606.8196590170387;3723.94414083789;3773.584905660375;4276.273033584298;32683.509747353808;32830.507542386884;32912.6708732912;32960;32980.50529242039;2905.302727345443;3007.6090108286003;3019.8037127586636;3064.6628870824193;3163.4147682678777;311920;312728;313354;313451.4327428371;313486;58796.706016469645;59787;67511.66244168846;73693.63153184307;74905.62547187229;68505;68617;68718.31281687219;68724.3127568728;68864.31135688678;18752.81247187538;18947.526311842037;18961.715574266396;18965.525861853563;18974;13353.66615834612;13441.327933603476;13442.126261792951;13490.460381584626;13615.455381784617;21989.560208795734;22130.778692213193;22170.88914555449;22207.888960555094;22253.554928901656;8131.38153521689;8501.53241571707;8513.489190648581;8544.31645468361;8607.751875977949;1048.8007278617038;1069.2194697870598;1138.9430528473542;1162.412981444382;1166.1894982986744;30438.84780576083;30663.693363066082;31112.533312000338;31200.843995779876;31459.213519662193;1107.4352080438873;1108.8613923259593;1142.4516232208587;1192.6958625550428;1193.7493126443544;14287.499937502138;14575.056399608713;14717.190554519604;15043.398264069534;15718.921405393128;277940;279182;281099;281538;283435;52878;53133.46866531285;53288.46711532835;53303.46696533063;53315;1375.3329635126956;1408.64783804049;1460.773580095087;1481.5481278210034;1489.9180545070126;28630;28686;28927;28976.855115724287;29513.409731805674;46146.53853461422;46474.76762616165;46556.30165547452;46577.53422465732;46619.30071048869;268310;270608;270991;271873;273048;36062;36748.81625591855;36938.076548086516;37006.814965926074;37204.813975929945;47391.763041184575;48461;48563.75718121528;48894.51105489042;48963.26555101605;40170.39744403778;40400.595994039686;40841.59158408497;41205.381919270636;41809.37285940774;39327.6067239324;39939.40090898581;40555.59444405636;40664;41071;38834.41748373833;39520.2095958085;39787.40318895277;39920.20159596734;39925.60074399219;34840.47739283862;35146.82426587851;35948.28103437969;36004.4599331005;36878.44682329821;27327.316817079733;27758.5836212461;27787;28186.577201341584;29459.85270073636 02:08:52 INFO - 2015-12-29 02:08:52,207 DEBUG : BROWSER_OUTPUT: |2;jquery.html;375.9342115129853;380.7093951504399;381.0587848015402;383.14558534468136;385.79552836996385;16168.353265869351;18076.36732714355;18116.456506304814;19434.805651943476;19442.611147777054;18673.62652746944;18877.811221887776;18997;19291.228350865982;19627.705584416246;5765.1928729977835;5907.970460147693;5910.379410161936;5914;5916.822495325147;4996.050750357421;5105.719185444799;5816.33112192097;5896.174535565025;5897.852553686149;3790.9620903791024;3865.8067096645195;5027.472115427882;5056.469070747574;5058.215976523645;5119.846404607868;5144.794208231666;5201.55786758126;5223.503767142115;5233.842984710445;4438.755868427235;4511.436070491189;4514.796834142471;4525.909481810345;4561.019380833137;15150.772738408932;15308.311125999357;15361.385544578203;15448.536543903701;15676.608084797972;2266.048259730917;2296.2651951375565;2302.5970455170277;2327.2320134355623;2443.841150325223;10992.945035274852;11107.666769996913;11152.721181970515;11180.944095279552;11343.886561134366;1813.8186181381757;1854.88870667759;1864.263615871723;1911.3788018893915;1927.5662975830326;66222.33777662161;66566.00452979628;67961.320386796;69779.30220697829;70042.29957700409;7793.571353575494;7797.376209903194;7945.523268603787;7970.043594768549;7979.9601001994615;9370.85943710845;9384.577694003787;9386.85919711205;9393.530323483936;9396.06039396055;6286.779962701284;6436.16329877113;6452.677366131768;6472.708728107246;6476.481881449473;6714.362135597208;6743.730250789913;6757.256701762803;6765.188177418643;6782.660866956632;3617.981910090485;3630.7640003399692;3652.1052342175803;3687.483752274684;3687.8198976327853;1245.6948047728365;1246.0903540415547;1248.1200753468784;1271.5930902111236;1292.3667402972476;461.78296200785576;473.8081077163685;522.628933457242;528.20241435432;542.842575653064;12428.62714118578;12504.437300321508;12733.426995785212;12792.552260670831;12855.421506032251;496.77396784462985;531.6809914051615;536.0404875273296;539.8380485854228;549.9505044546011;6920.619365934922;7152.642367881585;7277.16312624055;7353.080864891888;7375.852482950311;607162;616360;632292;632718;639682;75269.48383097032;82674.58662706768;82923.58538207391;83044.58477707693;83698;560.8121279371456;573.2576313673802;575.2751533068364;575.6114622629708;576.0926540698417;11499.137564682514;11757.706057348634;11960.700982475508;11960.76078478443;12210.450529726006;38558.42162367511;38778.41832372573;39592;39809.20381592294;39979.60020399759;52313;52772.20841687454;52976.73511632417;53996.190057148386;54466.72766636142;7293.088363954505;7395.556266624019;7400.407967362706;7401.962990185015;7412.925870741224;13091;13106.082574219714;13220.206787592775;13420;13427.462901483832;11600.071994240609;11627.941860290644;11642.76714465723;11645.359505226956;11798.941005294919;11275.943620281847;11384.373859437479;11410.429478525875;11466.598669046543;11637.243579167327;11554.76890462203;11658.88341116578;11738.70653233676;11852.58515951972;11869.703257418634;6538.019297105472;7255.637218138966;7440.330370266702;7445.590492522747;7471.589062601609;11406;11435.428228588371;11490.88509114898;11614.593489227836;11675.299482031105 02:08:52 INFO - 2015-12-29 02:08:52,208 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1475.675351422687;1539.699758547083;1651.8513333799956;1719.5787032177118;1887.6413481438517;6458.903116453251;6573.70418331175;6592.406683398493;7137.10786151731;7253.455990800685;7579.090509138911;7780.06639203295;7849;7851.057873055227;7854.136045035056;2382.9046838126496;2384.630382290739;2410.2287268074224;2413.0227257960555;2414.4446777241155;2105.768365479804;2141.7001619773246;2343.0744855781954;2375.7505461926507;2422.9636555451684;2317.8261630377706;2327.8719670418122;2329.301209637102;2330.5222429401933;2331.079223706635;2348.859068455898;2363.988180059106;2366.349253955159;2366.3965688749345;2368.6447032945025;2330.242671131872;2354.482013956927;2355.387599224197;2358.634411666177;2359.4809141988735;7891.21087891212;7930.0880398753725;7936.6825326986855;7940.086890007666;7951.20487951206;4802.735849528274;4835.564799168056;4857.902841943177;4860.8055677772845;4863.8540843774745;3077;3077.5229839374933;3110.0358888744336;3112.813231206112;3121.5317702344605;995.3381001633904;1006.0744115413763;1020.8264595018865;1022.5705203814416;1063.7127975446663;10463.424511651856;10544.367337959748;10561.78876422475;10567.73580660482;10570.682879513626;4371.409859668904;4374.278244089754;4434.268345722984;4439.556044395535;4444.022315090675;9083.45499270046;9089.590968406437;9102.544872756469;9107.726768196964;9108.225800806873;4832.4684284728655;4832.758362081881;4835.6131509479155;4835.637327200474;4840.540148685869;4370.060437006059;4370.104128653618;4371.606555410029;4375.47494300686;4378.1900348435;3572.732045096628;3798.3732684106812;3852.8739751803523;3875.3024455597697;3890.9416358754643;1142.126273400849;1145.3414286785132;1202.2666173634077;1202.7053371923935;1245.3275231375137;1196.916215864881;1242.142921384242;1290.9741805163844;1513.129950278572;1514.0158896717003;7511.8497630047095;7751.1861254568585;7752.922470775333;7759.456838021397;7761;1201.3932963853372;1222.407132540717;1232.8335674683983;1240.460399726122;1244.4089057697522;6049.5462840287155;6097.603655762361;6144.170536977451;6161.6611086389785;6165.10605962133;9298.86051709225;9498.905010949802;9509.762255943519;9512.38169518979;9516.429014259167;9298.163165315154;9411.29415293842;9432.292578056673;9457.66898158575;9458.668946586837;7364.77905662831;7489.063867016732;7495.287947644965;7496.475246732676;7500.174980752113;7232.312930271616;7326.890096648661;7356.926430735624;7357.852842943218;7358.889616655647;8303.4602750821;8360.958195208985;8389.95805020971;8410.074891761778;8414.242718155396;8492;8627.051024387438;8645.827083458422;8646.78383040429;8653.480791152551;7157.534760240567;7194.460415468965;7198.928010719826;7213.495055346285;7224.422046236184;7017.754378596725;7196.280371962762;7727.150013498398;7789.221077892165;7791.610419479117;7331.521847715194;7348.448866335153;7384.704611815682;7403.925960740324;7450.8882366763455;7169.892451613126;7198.316159964795;7211.38703210212;7282.453815963718;7295.963520182365;7283.490155689051;7293.0519032525435;7296.416286697157;7323.707051717872;7349.522281051714;6653.833654158491;6798.388145066968;6803.251642319242;6816.2842901496615;6817.5909445433435;6875.931240687529;6890;6895.448364130757;6917.688704008231;6939.653017349011 02:08:52 INFO - 2015-12-29 02:08:52,209 DEBUG : BROWSER_OUTPUT: |4;prototype.html;359.1846508425875;360.3549646133421;362.13811129511777;365.92132691471335;366.88076375178076;5787.13193021047;5904.822855314342;5906;5937.881242375155;5941.851453713659;5725.856853578663;5774.769009239625;5827.912581311284;5834.241548598687;5834.474897259246;4583.770811459431;4585.931211031837;4586.19741545229;4588.724676519403;4594.908101837961;4578.679492435531;4581.221192397301;4585.839495617654;4588.9541104589025;4589.173948689235;4495.551535277929;4556.954430455703;4601.769911504428;4609.562091601292;4640.814367425316;4617.353570500132;4622.514635963209;4630.560096790817;4631.444226692818;4647.442306923174;3957.2679054375035;4118.794060297003;4135.31767258405;4137.420761093449;4139.2135494256;5485.0949593317055;5527.55779537636;5588.245586845765;5596.048671725816;5634.3802181759975;5261.789528418859;5276.736163191864;5279.47205279469;5279.498447647467;5281.815136470205;6139.815805525796;6150.569460137792;6151.569390142692;6157.7229024694;6158.876822463526;4364.214441400547;4412.073464572443;4418.889527761799;4424.734515929023;4498.617617502517;6333.493320534346;6396.488280937514;6400.615963042186;6405.5516113872045;6413.615183088983;4517.774111294422;4518.254488009442;4523.298888672195;4524.298733696217;4524.434445694288;4548.749818759934;4574.862754117382;4581.770911454414;4588.449386073626;4591.311303304531;5138.691678499302;5226.425093239741;5230.006298803215;5245.924585459971;5250.816221432232;6283.96858015707;6288.434040936338;6288.717007734663;6289.55973081889;6294.748210071546;1660.825613310585;1676.2791999440115;1679.9580010499835;1681.9992104697685;1682.6718789835863;1132.796096702585;1133.0765426177638;1134.1097238667605;1136.9033632141195;1163.6741712320563;6073.5748497604745;6073.939260607337;6074.574779765374;6076.848078798065;6080.179175811209;6434.067060276234;6436.259830119502;6440.388163124496;6443.903341449882;6452.870942581122;5967.104934259895;6055.788047418408;6056.848578785565;6062.939370606326;6068.362821903725;3551.2187318789825;3568.232829941575;3568.6074531801482;3568.91077723059;3570.9822700530226;6403.871922561522;6515.12045873801;6516.674166291667;6617.172853393326;6639.43564796988;6578.901316480256;6592.967035164794;6600.1089852869245;6610.471162307004;6630.6353150576215;6643.435307998874;6663.100481435041;6664;6667.633280169637;6676.26561078281;6368.554201205871;6479.8056058318325;6480.902786458301;6483.740650374121;6512.967435162794;6416.262129855127;6433.163688720437;6435.742570297323;6484.254310754323;6487.124238227968;6711.496637752286;6715.664216789043;6716.597004179569;6720.563163394364;6723.596584204964;4517.390152329525;4583.037562111994;4587.7706114693465;4593;4593.678442508993;6545.770898018546;6551.50863685216;6553.0170474429215;6556.868862622816;6570.8028759137305;6384.968075159594;6397.520185985976;6405.8718825624155;6407.615543067431;6409.807705768834;6279.645133075097;6385.553011289165;6396.200474940632;6396.840078998062;6400.71196796136;6275.466585340298;6276.152719382825;6278;6280.340564240872;6286.339934307015;6404.839879003062;6407.359264073555;6407.391297826699;6411.839704007437;6418.582792118497;6181.598196117238;6246.156768836149;6262.906056409066;6272.560920735505;6274.749010039547 02:08:52 INFO - 2015-12-29 02:08:52,210 DEBUG : BROWSER_OUTPUT: |5;yui.html;849.7535714642753;934.2012579244749;936.9531523423827;949.7388218239979;957.6456711016923;11847.822282665757;12280.078994075435;12315.507379704812;12524.937375313133;12686.175398599085;12711.42798574064;13986.720265594708;14094.64763380916;14112.15327080376;14128.293585320744;755.939524838013;896.3098414221046;900.1133883125149;900.2168113741054;900.3922352412128;896.2113340260562;901;901.9594118264661;902.2331018634142;903.0563061600643;795.1253621016893;796.1083586383259;947.8957314695413;948.5494390164686;949.7340744591526;923.0215971070686;932.995335023324;933.6545478173051;934.1499235695485;934.4766930518931;690.92399836018;692.2419950154573;692.5221597097986;693.3482526425145;699.0248603198561;13578.796318055238;13831.377588008563;13855.307234638227;13857.722845543032;13859.722805543934;13010.349482525837;13616;14553.563393098224;14565.198914059722;14769.852301477062;11623.302601843916;11665.70835729105;11689.357085360301;11764.235324703866;11857.88142118585;3577.5528058992627;3600.3879340512176;3604.2611264690677;3618.873339433107;3630.9273814523826;50642.24036639454;51057.777601577785;51187.488125118645;51384.74307628475;51656.74171629155;8520.957395212985;8592.226699597066;8683.478991260545;8751.56242187888;8965.193132618097;13845.861541384658;13864.930675346559;13872.306384680927;13907;13914.791278130837;7499.550026998398;7561.7731468056045;7575.545467271982;7587.734429295058;7652.846943061108;4629.282461218517;4645.907081858344;4685.250359942393;4685.5782979532005;4704.7412392318065;3050.435669401168;3070.738987186114;3074.6003019607306;3077.215310095956;3077.784555081123;478.1369627821815;479.063430992409;485.85667228167773;492.2468623006734;492.91374009548184;689.2039694153261;714.639107250835;723.4284914917157;740.7111226621622;746.5595298773641;9087.36388452802;9363.157315841607;9452.243820494345;9695;9885.159761420216;562.3111688181945;713.600383785082;720.1825927572139;765.1430397954216;816.1919699497455;4472.306792447176;4649.5815376616265;4770.332153498513;4808.2478334495745;4832.830850920272;65105.674471628285;65910.34089659042;66481.6675916627;66662;66883;58951.705241474374;59649.40350596525;59720;59939.400605994255;60907.08639370324;53623.78257304574;54007.18989215165;54571;54954.5858771574;55271.72364138153;9303.86044209351;9395.107464790834;9423.717288481357;9467.431954082776;9551.856722149312;39929.60070399375;40064;40079.1984160321;40803.59196408117;41429.171416572106;49344.506554935426;52323.47676523186;52401;52987.20519192292;53272.73363633312;8046.476978996347;8259.752207433787;8511.489310641133;8588.484690918565;8704.216620504185;13893.305334733424;14039.50861719835;14189.716205676035;14394.856051439352;14456.060356076821;12206.633800985985;12396.132270741322;12408.56570020045;12436.19164754288;13006.089573729749;12453.688657783629;12620.368981550702;12813.423395947018;12899.871001289866;12942.870571294543;11589.362585057566;11818.468168932246;11864.584739534075;12103.818442723543;12139.817902731642;9021.909780902108;9703.417794932328;9918.05778451046;10295.639652612123;10521.316114452537;8994.459214351484;9346.20557252641;9549.952250238704;9958.950205248928;10054.19566434698 02:08:52 INFO - 2015-12-29 02:08:52,210 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:08:52 INFO - 2015-12-29 02:08:52,210 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:08:52 INFO - 2015-12-29 02:08:52,210 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,802 02:08:52 INFO - 2015-12-29 02:08:52,210 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:08:52 INFO - 2015-12-29 02:08:52,210 DEBUG : BROWSER_OUTPUT: __startTimestamp1451383732202__endTimestamp 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: Number of tests: 6 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5644.42 Median:5858.27 stddev:640.18 (10.9%) stddev-sans-first:615.37 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: Values: 3309.3 3394.5 3405.0 3410.7 3439.9 5566.2 5586.4 5631.4 5632.9 5640.0 5627.1 5643.9 5724.8 5735.5 5748.7 4953.7 5150.9 5178.3 5257.6 5283.6 4996.4 5103.7 5115.2 5143.3 5237.8 5122.5 5126.1 5151.3 5152.0 5161.2 4962.4 5146.3 5155.0 5156.7 5204.3 5014.6 5075.0 5146.3 5158.1 5176.3 5431.4 5446.5 5450.5 5460.3 5471.4 5876.6 6003.4 6093.2 6102.8 6107.0 6030.6 6075.0 6097.8 6100.1 6103.4 5716.6 5719.9 5720.8 5738.2 5769.0 6010.5 6013.1 6126.4 6167.5 6186.7 5977.3 6009.7 6013.1 6044.2 6064.9 6098.2 6114.0 6116.6 6121.0 6121.1 5798.8 5900.7 6009.8 6088.4 6095.9 6111.8 6115.8 6123.6 6140.3 6165.3 4656.2 4664.4 4669.2 4691.6 4692.2 4026.6 4073.8 4076.2 4148.2 4189.3 5778.9 5841.5 5843.1 5845.0 5850.9 6156.0 6174.2 6193.0 6193.6 6203.9 5524.3 5590.6 5628.5 5675.5 5688.9 5310.0 5349.2 5349.4 5352.6 5353.5 6153.0 6369.9 6375.9 6378.6 6380.0 6017.1 6047.6 6161.7 6167.5 6168.2 6301.6 6309.5 6313.0 6314.5 6316.5 5816.9 5899.1 6035.1 6047.2 6047.3 6022.1 6111.0 6113.9 6115.0 6116.5 5925.2 6006.2 6017.6 6018.5 6018.8 5745.4 5791.9 5830.9 5999.0 6025.4 5926.8 5931.5 5934.5 5935.5 5957.0 5944.2 6030.8 6032.2 6034.2 6048.6 5685.1 5765.9 5858.3 5916.7 5986.4 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48238.43 Median:17923.46 stddev:75090.77 (419.0%) stddev-sans-first:75216.15 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: Values: 578.9 611.5 613.8 614.0 628.4 34925.5 42615.8 43073.8 43129.6 43133.8 46440.0 46975.3 47011.8 47056.8 47100.5 8115.7 8388.9 9254.6 9430.2 10724.2 10972.8 11037.1 11081.9 11091.4 11099.5 11048.8 11166.7 11174.9 11177.1 11181.1 10931.3 11008.1 11010.6 11028.4 11041.0 10720.8 10819.7 10847.8 10848.5 10854.3 43054.6 43487.1 43607.3 43628.0 43645.0 3603.5 3606.8 3723.9 3773.6 4276.3 32683.5 32830.5 32912.7 32960.0 32980.5 2905.3 3007.6 3019.8 3064.7 3163.4 311920.0 312728.0 313354.0 313451.4 313486.0 58796.7 59787.0 67511.7 73693.6 74905.6 68505.0 68617.0 68718.3 68724.3 68864.3 18752.8 18947.5 18961.7 18965.5 18974.0 13353.7 13441.3 13442.1 13490.5 13615.5 21989.6 22130.8 22170.9 22207.9 22253.6 8131.4 8501.5 8513.5 8544.3 8607.8 1048.8 1069.2 1138.9 1162.4 1166.2 30438.8 30663.7 31112.5 31200.8 31459.2 1107.4 1108.9 1142.5 1192.7 1193.7 14287.5 14575.1 14717.2 15043.4 15718.9 277940.0 279182.0 281099.0 281538.0 283435.0 52878.0 53133.5 53288.5 53303.5 53315.0 1375.3 1408.6 1460.8 1481.5 1489.9 28630.0 28686.0 28927.0 28976.9 29513.4 46146.5 46474.8 46556.3 46577.5 46619.3 268310.0 270608.0 270991.0 271873.0 273048.0 36062.0 36748.8 36938.1 37006.8 37204.8 47391.8 48461.0 48563.8 48894.5 48963.3 40170.4 40400.6 40841.6 41205.4 41809.4 39327.6 39939.4 40555.6 40664.0 41071.0 38834.4 39520.2 39787.4 39920.2 39925.6 34840.5 35146.8 35948.3 36004.5 36878.4 27327.3 27758.6 27787.0 28186.6 29459.9 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,211 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30695.57 Median:4543.46 stddev:102479.18 (2255.5%) stddev-sans-first:102741.37 02:08:52 INFO - 2015-12-29 02:08:52,212 DEBUG : BROWSER_OUTPUT: Values: 375.9 380.7 381.1 383.1 385.8 16168.4 18076.4 18116.5 19434.8 19442.6 18673.6 18877.8 18997.0 19291.2 19627.7 5765.2 5908.0 5910.4 5914.0 5916.8 4996.1 5105.7 5816.3 5896.2 5897.9 3791.0 3865.8 5027.5 5056.5 5058.2 5119.8 5144.8 5201.6 5223.5 5233.8 4438.8 4511.4 4514.8 4525.9 4561.0 15150.8 15308.3 15361.4 15448.5 15676.6 2266.0 2296.3 2302.6 2327.2 2443.8 10992.9 11107.7 11152.7 11180.9 11343.9 1813.8 1854.9 1864.3 1911.4 1927.6 66222.3 66566.0 67961.3 69779.3 70042.3 7793.6 7797.4 7945.5 7970.0 7980.0 9370.9 9384.6 9386.9 9393.5 9396.1 6286.8 6436.2 6452.7 6472.7 6476.5 6714.4 6743.7 6757.3 6765.2 6782.7 3618.0 3630.8 3652.1 3687.5 3687.8 1245.7 1246.1 1248.1 1271.6 1292.4 461.8 473.8 522.6 528.2 542.8 12428.6 12504.4 12733.4 12792.6 12855.4 496.8 531.7 536.0 539.8 550.0 6920.6 7152.6 7277.2 7353.1 7375.9 607162.0 616360.0 632292.0 632718.0 639682.0 75269.5 82674.6 82923.6 83044.6 83698.0 560.8 573.3 575.3 575.6 576.1 11499.1 11757.7 11960.7 11960.8 12210.5 38558.4 38778.4 39592.0 39809.2 39979.6 52313.0 52772.2 52976.7 53996.2 54466.7 7293.1 7395.6 7400.4 7402.0 7412.9 13091.0 13106.1 13220.2 13420.0 13427.5 11600.1 11627.9 11642.8 11645.4 11798.9 11275.9 11384.4 11410.4 11466.6 11637.2 11554.8 11658.9 11738.7 11852.6 11869.7 6538.0 7255.6 7440.3 7445.6 7471.6 11406.0 11435.4 11490.9 11614.6 11675.3 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5569.06 Median:6583.06 stddev:2815.73 (42.8%) stddev-sans-first:2806.81 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: Values: 1475.7 1539.7 1651.9 1719.6 1887.6 6458.9 6573.7 6592.4 7137.1 7253.5 7579.1 7780.1 7849.0 7851.1 7854.1 2382.9 2384.6 2410.2 2413.0 2414.4 2105.8 2141.7 2343.1 2375.8 2423.0 2317.8 2327.9 2329.3 2330.5 2331.1 2348.9 2364.0 2366.3 2366.4 2368.6 2330.2 2354.5 2355.4 2358.6 2359.5 7891.2 7930.1 7936.7 7940.1 7951.2 4802.7 4835.6 4857.9 4860.8 4863.9 3077.0 3077.5 3110.0 3112.8 3121.5 995.3 1006.1 1020.8 1022.6 1063.7 10463.4 10544.4 10561.8 10567.7 10570.7 4371.4 4374.3 4434.3 4439.6 4444.0 9083.5 9089.6 9102.5 9107.7 9108.2 4832.5 4832.8 4835.6 4835.6 4840.5 4370.1 4370.1 4371.6 4375.5 4378.2 3572.7 3798.4 3852.9 3875.3 3890.9 1142.1 1145.3 1202.3 1202.7 1245.3 1196.9 1242.1 1291.0 1513.1 1514.0 7511.8 7751.2 7752.9 7759.5 7761.0 1201.4 1222.4 1232.8 1240.5 1244.4 6049.5 6097.6 6144.2 6161.7 6165.1 9298.9 9498.9 9509.8 9512.4 9516.4 9298.2 9411.3 9432.3 9457.7 9458.7 7364.8 7489.1 7495.3 7496.5 7500.2 7232.3 7326.9 7356.9 7357.9 7358.9 8303.5 8361.0 8390.0 8410.1 8414.2 8492.0 8627.1 8645.8 8646.8 8653.5 7157.5 7194.5 7198.9 7213.5 7224.4 7017.8 7196.3 7727.2 7789.2 7791.6 7331.5 7348.4 7384.7 7403.9 7450.9 7169.9 7198.3 7211.4 7282.5 7296.0 7283.5 7293.1 7296.4 7323.7 7349.5 6653.8 6798.4 6803.3 6816.3 6817.6 6875.9 6890.0 6895.4 6917.7 6939.7 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5282.57 Median:6011.45 stddev:1554.73 (25.9%) stddev-sans-first:1514.54 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: Values: 359.2 360.4 362.1 365.9 366.9 5787.1 5904.8 5906.0 5937.9 5941.9 5725.9 5774.8 5827.9 5834.2 5834.5 4583.8 4585.9 4586.2 4588.7 4594.9 4578.7 4581.2 4585.8 4589.0 4589.2 4495.6 4557.0 4601.8 4609.6 4640.8 4617.4 4622.5 4630.6 4631.4 4647.4 3957.3 4118.8 4135.3 4137.4 4139.2 5485.1 5527.6 5588.2 5596.0 5634.4 5261.8 5276.7 5279.5 5279.5 5281.8 6139.8 6150.6 6151.6 6157.7 6158.9 4364.2 4412.1 4418.9 4424.7 4498.6 6333.5 6396.5 6400.6 6405.6 6413.6 4517.8 4518.3 4523.3 4524.3 4524.4 4548.7 4574.9 4581.8 4588.4 4591.3 5138.7 5226.4 5230.0 5245.9 5250.8 6284.0 6288.4 6288.7 6289.6 6294.7 1660.8 1676.3 1680.0 1682.0 1682.7 1132.8 1133.1 1134.1 1136.9 1163.7 6073.6 6073.9 6074.6 6076.8 6080.2 6434.1 6436.3 6440.4 6443.9 6452.9 5967.1 6055.8 6056.8 6062.9 6068.4 3551.2 3568.2 3568.6 3568.9 3571.0 6403.9 6515.1 6516.7 6617.2 6639.4 6578.9 6593.0 6600.1 6610.5 6630.6 6643.4 6663.1 6664.0 6667.6 6676.3 6368.6 6479.8 6480.9 6483.7 6513.0 6416.3 6433.2 6435.7 6484.3 6487.1 6711.5 6715.7 6716.6 6720.6 6723.6 4517.4 4583.0 4587.8 4593.0 4593.7 6545.8 6551.5 6553.0 6556.9 6570.8 6385.0 6397.5 6405.9 6407.6 6409.8 6279.6 6385.6 6396.2 6396.8 6400.7 6275.5 6276.2 6278.0 6280.3 6286.3 6404.8 6407.4 6407.4 6411.8 6418.6 6181.6 6246.2 6262.9 6272.6 6274.7 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15041.63 Median:52694.10 stddev:18406.57 (34.9%) stddev-sans-first:18427.36 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: Values: 849.8 934.2 937.0 949.7 957.6 11847.8 12280.1 12315.5 12524.9 12686.2 12711.4 13986.7 14094.6 14112.2 14128.3 755.9 896.3 900.1 900.2 900.4 896.2 901.0 902.0 902.2 903.1 795.1 796.1 947.9 948.5 949.7 923.0 933.0 933.7 934.1 934.5 690.9 692.2 692.5 693.3 699.0 13578.8 13831.4 13855.3 13857.7 13859.7 13010.3 13616.0 14553.6 14565.2 14769.9 11623.3 11665.7 11689.4 11764.2 11857.9 3577.6 3600.4 3604.3 3618.9 3630.9 50642.2 51057.8 51187.5 51384.7 51656.7 8521.0 8592.2 8683.5 8751.6 8965.2 13845.9 13864.9 13872.3 13907.0 13914.8 7499.6 7561.8 7575.5 7587.7 7652.8 4629.3 4645.9 4685.3 4685.6 4704.7 3050.4 3070.7 3074.6 3077.2 3077.8 478.1 479.1 485.9 492.2 492.9 689.2 714.6 723.4 740.7 746.6 9087.4 9363.2 9452.2 9695.0 9885.2 562.3 713.6 720.2 765.1 816.2 4472.3 4649.6 4770.3 4808.2 4832.8 65105.7 65910.3 66481.7 66662.0 66883.0 58951.7 59649.4 59720.0 59939.4 60907.1 53623.8 54007.2 54571.0 54954.6 55271.7 9303.9 9395.1 9423.7 9467.4 9551.9 39929.6 40064.0 40079.2 40803.6 41429.2 49344.5 52323.5 52401.0 52987.2 53272.7 8046.5 8259.8 8511.5 8588.5 8704.2 13893.3 14039.5 14189.7 14394.9 14456.1 12206.6 12396.1 12408.6 12436.2 13006.1 12453.7 12620.4 12813.4 12899.9 12942.9 11589.4 11818.5 11864.6 12103.8 12139.8 9021.9 9703.4 9918.1 10295.6 10521.3 8994.5 9346.2 9550.0 9959.0 10054.2 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:08:52 INFO - 2015-12-29 02:08:52,213 DEBUG : BROWSER_OUTPUT: 02:08:52 INFO - 2015-12-29 02:08:52,785 INFO : Browser exited with error code: 0 02:08:52 INFO - 2015-12-29 02:08:52,801 INFO : Completed test dromaeo_css (00:18:28) 02:08:52 INFO - 2015-12-29 02:08:52,801 INFO : Starting test kraken 02:08:52 INFO - 2015-12-29 02:08:52,801 DEBUG : operating with platform_type : linux_ 02:08:52 INFO - 2015-12-29 02:08:52,802 INFO : Initialising browser for kraken test... 02:08:52 INFO - 2015-12-29 02:08:52,808 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkP6Fgf/profile http://localhost:33734/getInfo.html 02:08:56 INFO - 2015-12-29 02:08:56,030 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:08:56 INFO - 2015-12-29 02:08:56,031 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:08:56 INFO - 2015-12-29 02:08:56,031 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:08:56 INFO - 2015-12-29 02:08:56,031 DEBUG : BROWSER_OUTPUT: __metrics 02:08:56 INFO - 2015-12-29 02:08:56,119 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] 02:08:57 INFO - 2015-12-29 02:08:57,313 INFO : Browser initialized. 02:08:57 INFO - 2015-12-29 02:08:57,314 INFO : Running cycle 1/1 for kraken test... 02:08:57 INFO - 2015-12-29 02:08:57,314 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkP6Fgf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:09:08 INFO - 2015-12-29 02:09:08,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 131088384 02:09:08 INFO - 2015-12-29 02:09:08,849 DEBUG : BROWSER_OUTPUT: 02:09:11 INFO - 2015-12-29 02:09:11,283 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33734/tests/kraken/driver.html?testName=audio-beat-detection) 02:09:11 INFO - 2015-12-29 02:09:11,850 DEBUG : BROWSER_OUTPUT: RSS: Main: 164593664 02:09:11 INFO - 2015-12-29 02:09:11,850 DEBUG : BROWSER_OUTPUT: 02:09:14 INFO - 2015-12-29 02:09:14,412 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33734/tests/kraken/driver.html?testName=audio-dft) 02:09:14 INFO - 2015-12-29 02:09:14,978 DEBUG : BROWSER_OUTPUT: RSS: Main: 160980992 02:09:14 INFO - 2015-12-29 02:09:14,978 DEBUG : BROWSER_OUTPUT: 02:09:17 INFO - 2015-12-29 02:09:17,774 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33734/tests/kraken/driver.html?testName=audio-fft) 02:09:18 INFO - 2015-12-29 02:09:18,336 DEBUG : BROWSER_OUTPUT: RSS: Main: 158076928 02:09:18 INFO - 2015-12-29 02:09:18,336 DEBUG : BROWSER_OUTPUT: 02:09:20 INFO - 2015-12-29 02:09:20,288 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33734/tests/kraken/driver.html?testName=audio-oscillator) 02:09:20 INFO - 2015-12-29 02:09:20,849 DEBUG : BROWSER_OUTPUT: RSS: Main: 158576640 02:09:20 INFO - 2015-12-29 02:09:20,849 DEBUG : BROWSER_OUTPUT: 02:09:22 INFO - 2015-12-29 02:09:22,987 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33734/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:09:23 INFO - 2015-12-29 02:09:23,553 DEBUG : BROWSER_OUTPUT: RSS: Main: 157081600 02:09:23 INFO - 2015-12-29 02:09:23,553 DEBUG : BROWSER_OUTPUT: 02:09:26 INFO - 2015-12-29 02:09:26,418 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33734/tests/kraken/driver.html?testName=imaging-darkroom) 02:09:26 INFO - 2015-12-29 02:09:26,987 DEBUG : BROWSER_OUTPUT: RSS: Main: 157442048 02:09:26 INFO - 2015-12-29 02:09:26,988 DEBUG : BROWSER_OUTPUT: 02:09:30 INFO - 2015-12-29 02:09:30,331 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33734/tests/kraken/driver.html?testName=imaging-desaturate) 02:09:30 INFO - 2015-12-29 02:09:30,897 DEBUG : BROWSER_OUTPUT: RSS: Main: 158711808 02:09:30 INFO - 2015-12-29 02:09:30,898 DEBUG : BROWSER_OUTPUT: 02:09:33 INFO - 2015-12-29 02:09:33,592 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33734/tests/kraken/driver.html?testName=json-parse-financial) 02:09:34 INFO - 2015-12-29 02:09:34,158 DEBUG : BROWSER_OUTPUT: RSS: Main: 157945856 02:09:34 INFO - 2015-12-29 02:09:34,158 DEBUG : BROWSER_OUTPUT: 02:09:35 INFO - 2015-12-29 02:09:35,658 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33734/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:09:36 INFO - 2015-12-29 02:09:36,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 162639872 02:09:36 INFO - 2015-12-29 02:09:36,220 DEBUG : BROWSER_OUTPUT: 02:09:37 INFO - 2015-12-29 02:09:37,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:09:38 INFO - 2015-12-29 02:09:38,544 DEBUG : BROWSER_OUTPUT: RSS: Main: 159707136 02:09:38 INFO - 2015-12-29 02:09:38,544 DEBUG : BROWSER_OUTPUT: 02:09:40 INFO - 2015-12-29 02:09:40,507 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:09:41 INFO - 2015-12-29 02:09:41,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 165011456 02:09:41 INFO - 2015-12-29 02:09:41,076 DEBUG : BROWSER_OUTPUT: 02:09:43 INFO - 2015-12-29 02:09:43,368 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:09:43 INFO - 2015-12-29 02:09:43,949 DEBUG : BROWSER_OUTPUT: RSS: Main: 169234432 02:09:43 INFO - 2015-12-29 02:09:43,949 DEBUG : BROWSER_OUTPUT: 02:09:46 INFO - 2015-12-29 02:09:46,363 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:09:46 INFO - 2015-12-29 02:09:46,929 DEBUG : BROWSER_OUTPUT: RSS: Main: 168181760 02:09:46 INFO - 2015-12-29 02:09:46,929 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,518 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33734/tests/kraken/driver.html?testName=ai-astar) 02:09:48 INFO - 2015-12-29 02:09:48,776 DEBUG : BROWSER_OUTPUT: RSS: Main: 226062336 02:09:48 INFO - 2015-12-29 02:09:48,776 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,776 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:09:48 INFO - 2015-12-29 02:09:48,776 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;104;109;109;110;100;109;101;110 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;120;154;159;128;147;154;125;162;121 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;158;160;160;161;161;162;162;159;161 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;70;71;147;71;71;71;70;164;70 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;89;136;88;90;89;143;90;90;139 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;109;110;109;109;110;110 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;147;146;145;145;145;145 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;96 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;64;53;55;54;55;57;52;52 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;64;63;63;63;62;64;63;63 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;76;77;78;77;81;78;78;77;76 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;109;107;113;116;113;110;115;113;115;110 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;132;140;133;129;136;138;147;134;134 02:09:48 INFO - 2015-12-29 02:09:48,777 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;61;62;61;60;67;60;62;62;60;61 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,860 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: __startTimestamp1451383788770__endTimestamp 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: Number of tests: 14 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.20 Median:109.00 stddev:3.99 (3.7%) stddev-sans-first:4.18 02:09:48 INFO - 2015-12-29 02:09:48,778 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 104.0 109.0 109.0 110.0 100.0 109.0 101.0 110.0 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.50 Median:154.00 stddev:16.93 (11.0%) stddev-sans-first:17.34 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: Values: 155.0 120.0 154.0 159.0 128.0 147.0 154.0 125.0 162.0 121.0 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: Values: 160.0 158.0 160.0 160.0 161.0 161.0 162.0 162.0 159.0 161.0 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.50 Median:70.50 stddev:36.07 (51.2%) stddev-sans-first:37.69 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 71.0 147.0 71.0 71.0 71.0 70.0 164.0 70.0 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.40 Median:89.50 stddev:24.17 (27.0%) stddev-sans-first:25.07 02:09:48 INFO - 2015-12-29 02:09:48,779 DEBUG : BROWSER_OUTPUT: Values: 90.0 89.0 136.0 88.0 90.0 89.0 143.0 90.0 90.0 139.0 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 109.0 110.0 109.0 109.0 110.0 110.0 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 147.0 146.0 145.0 145.0 145.0 145.0 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:3.46 (6.3%) stddev-sans-first:3.67 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 64.0 53.0 55.0 54.0 55.0 57.0 52.0 52.0 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 64.0 63.0 63.0 63.0 62.0 64.0 63.0 63.0 02:09:48 INFO - 2015-12-29 02:09:48,780 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:3.03 (3.9%) stddev-sans-first:1.51 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: Values: 86.0 76.0 77.0 78.0 77.0 81.0 78.0 78.0 77.0 76.0 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.10 Median:113.00 stddev:2.96 (2.6%) stddev-sans-first:2.92 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: Values: 109.0 107.0 113.0 116.0 113.0 110.0 115.0 113.0 115.0 110.0 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.40 Median:137.00 stddev:5.23 (3.8%) stddev-sans-first:5.28 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: Values: 141.0 132.0 140.0 133.0 129.0 136.0 138.0 147.0 134.0 134.0 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:2.07 (3.4%) stddev-sans-first:2.18 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: Values: 61.0 62.0 61.0 60.0 67.0 60.0 62.0 62.0 60.0 61.0 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:09:48 INFO - 2015-12-29 02:09:48,781 DEBUG : BROWSER_OUTPUT: 02:09:49 INFO - 2015-12-29 02:09:49,417 INFO : Browser exited with error code: 0 02:09:49 INFO - 2015-12-29 02:09:49,471 INFO : Completed test kraken (00:00:56) 02:09:49 INFO - 2015-12-29 02:09:49,471 INFO : Starting test v8_7 02:09:49 INFO - 2015-12-29 02:09:49,471 DEBUG : operating with platform_type : linux_ 02:09:49 INFO - 2015-12-29 02:09:49,471 INFO : Initialising browser for v8_7 test... 02:09:49 INFO - 2015-12-29 02:09:49,477 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpw0N0p2/profile http://localhost:33734/getInfo.html 02:09:52 INFO - 2015-12-29 02:09:52,779 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:09:52 INFO - 2015-12-29 02:09:52,779 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:09:52 INFO - 2015-12-29 02:09:52,779 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:09:52 INFO - 2015-12-29 02:09:52,779 DEBUG : BROWSER_OUTPUT: __metrics 02:09:52 INFO - 2015-12-29 02:09:52,901 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] 02:09:54 INFO - 2015-12-29 02:09:54,037 INFO : Browser initialized. 02:09:54 INFO - 2015-12-29 02:09:54,038 INFO : Running cycle 1/1 for v8_7 test... 02:09:54 INFO - 2015-12-29 02:09:54,039 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpw0N0p2/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:10:05 INFO - 2015-12-29 02:10:05,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 128241664 02:10:05 INFO - 2015-12-29 02:10:05,657 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,038 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33734/tests/v8_7/run.html (next: http://localhost:33734/tests/v8_7/run.html) 02:10:27 INFO - 2015-12-29 02:10:27,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 420007936 02:10:27 INFO - 2015-12-29 02:10:27,289 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,289 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:10:27 INFO - 2015-12-29 02:10:27,290 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:10:27 INFO - 2015-12-29 02:10:27,291 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:10:27 INFO - 2015-12-29 02:10:27,291 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:10:27 INFO - 2015-12-29 02:10:27,291 DEBUG : BROWSER_OUTPUT: |0;Richards;166.58337497917708 02:10:27 INFO - 2015-12-29 02:10:27,291 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.01020061203673 02:10:27 INFO - 2015-12-29 02:10:27,292 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.8622420524261 02:10:27 INFO - 2015-12-29 02:10:27,292 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4985.074626865671 02:10:27 INFO - 2015-12-29 02:10:27,293 DEBUG : BROWSER_OUTPUT: |4;RayTrace;935.4536950420954 02:10:27 INFO - 2015-12-29 02:10:27,293 DEBUG : BROWSER_OUTPUT: |5;Earley;563.3802816901408 02:10:27 INFO - 2015-12-29 02:10:27,293 DEBUG : BROWSER_OUTPUT: |6;Boyer;10589.473684210527 02:10:27 INFO - 2015-12-29 02:10:27,293 DEBUG : BROWSER_OUTPUT: |7;RegExp;23833.333333333332 02:10:27 INFO - 2015-12-29 02:10:27,293 DEBUG : BROWSER_OUTPUT: |8;Splay;536.7686527106817 02:10:27 INFO - 2015-12-29 02:10:27,293 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: __startTimestamp1451383827288__endTimestamp 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: Number of tests: 10 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.58 Median:166.58 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,294 DEBUG : BROWSER_OUTPUT: Values: 166.6 02:10:27 INFO - 2015-12-29 02:10:27,295 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,295 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.01 Median:170.01 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,295 DEBUG : BROWSER_OUTPUT: Values: 170.0 02:10:27 INFO - 2015-12-29 02:10:27,295 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,296 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.86 Median:278.86 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,296 DEBUG : BROWSER_OUTPUT: Values: 278.9 02:10:27 INFO - 2015-12-29 02:10:27,296 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4985.07 Median:4985.07 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: Values: 4985.1 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:935.45 Median:935.45 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: Values: 935.5 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:563.38 Median:563.38 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: Values: 563.4 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10589.47 Median:10589.47 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: Values: 10589.5 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23833.33 Median:23833.33 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,297 DEBUG : BROWSER_OUTPUT: Values: 23833.3 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:536.77 Median:536.77 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: Values: 536.8 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: Values: 4812.5 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:10:27 INFO - 2015-12-29 02:10:27,298 DEBUG : BROWSER_OUTPUT: 02:10:27 INFO - 2015-12-29 02:10:27,978 INFO : Browser exited with error code: 0 02:10:27 INFO - 2015-12-29 02:10:27,994 INFO : Completed test v8_7 (00:00:38) 02:10:28 INFO - 2015-12-29 02:10:28,301 INFO : Completed test suite (00:20:04) 02:10:28 INFO - 2015-12-29 02:10:28,302 DEBUG : Working with test: dromaeo_css 02:10:28 INFO - 2015-12-29 02:10:28,302 DEBUG : Generating results file: dromaeo_css 02:10:28 INFO - 2015-12-29 02:10:28,303 DEBUG : Working with test: kraken 02:10:28 INFO - 2015-12-29 02:10:28,304 DEBUG : Generating results file: kraken 02:10:28 INFO - 2015-12-29 02:10:28,304 INFO : javascript benchmark 02:10:28 INFO - 2015-12-29 02:10:28,304 DEBUG : Working with test: v8_7 02:10:28 INFO - 2015-12-29 02:10:28,304 DEBUG : Generating results file: v8_7 02:10:28 INFO - 2015-12-29 02:10:28,305 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:10:28 INFO - 2015-12-29 02:10:28,358 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 02:10:28 INFO - 2015-12-29 02:10:28,358 DEBUG : process_Request line: dromaeo_css 7099.59 graph.html#tests=[[72,53,35]] 02:10:28 INFO - 2015-12-29 02:10:28,358 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:10:28 INFO - 2015-12-29 02:10:28,392 DEBUG : process_Request line: kraken 1458.80 graph.html#tests=[[232,53,35]] 02:10:28 INFO - 2015-12-29 02:10:28,392 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:10:28 INFO - 2015-12-29 02:10:28,423 DEBUG : process_Request line: v8_7 23239.98 graph.html#tests=[[230,53,35]] 02:10:28 INFO - 2015-12-29 02:10:28,425 INFO : javascript benchmark 02:10:28 INFO - 2015-12-29 02:10:28,428 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3309.2719601687622, 3394.4738565522352, 3405.0125463615555, 3410.6759857813513, 3439.8968030959063, 5566.248556444879, 5586.413426590206, 5631.436856314366, 5632.8591785205335, 5639.971800141004, 5627.099664053752, 5643.943560564393, 5724.828255152342, 5735.512481439072, 5748.741306641201, 4953.727544985042, 5150.948490515084, 5178.300929374543, 5257.605679574028, 5283.6037297202865, 4996.425411077718, 5103.668261563005, 5115.232715092748, 5143.254228136918, 5237.816676416326, 5122.462141475129, 5126.128558145124, 5151.330327057477, 5152.046871328797, 5161.225816127592, 4962.429320628138, 5146.279520867081, 5155.020546096267, 5156.74216289184, 5204.271402003723, 5014.573761230264, 5074.974625126888, 5146.305248791403, 5158.097332966716, 5176.275321454962, 5431.429699881534, 5446.509814116709, 5450.454954504517, 5460.26286451328, 5471.37079235885, 5876.647401155901, 6003.399660034005, 6093.207882975229, 6102.755889764404, 6107.022876339809, 6030.638161710268, 6075.027995520695, 6097.817065488042, 6100.054491553817, 6103.359147289515, 5716.599838011382, 5719.885602287973, 5720.771169153229, 5738.225339579187, 5769.0, 6010.549208809357, 6013.0980352946535, 6126.36500017443, 6167.537434692326, 6186.721597528122, 5977.342492325841, 6009.699515024231, 6013.067974463965, 6044.1840351552855, 6064.878702425926, 6098.207233059763, 6114.0, 6116.633002019893, 6121.0, 6121.051237058263, 5798.82603521895, 5900.734466948998, 6009.789657362059, 6088.4215999479975, 6095.939040609625, 6111.7500336055855, 6115.756844843582, 6123.601965872204, 6140.293866205354, 6165.290991535941, 4656.231721765938, 4664.39362882823, 4669.182892993713, 4691.554302341272, 4692.249240121564, 4026.5973402660084, 4073.7759423232005, 4076.2255171517318, 4148.23257697327, 4189.2668782962855, 5778.855528611818, 5841.474267315877, 5843.065109582446, 5845.0, 5850.882982340415, 6156.045812899008, 6174.166487524127, 6192.969035154885, 6193.566450348522, 6203.937960620336, 5524.254225679482, 5590.608657394025, 5628.465295796893, 5675.5459563234845, 5688.914666280009, 5310.0, 5349.1976203569775, 5349.411564727877, 5352.598555108382, 5353.491418315254, 6152.969235153795, 6369.936300637027, 6375.904361434582, 6378.585391949508, 6380.0, 6017.0974353846395, 6047.606905551125, 6161.722722477589, 6167.537434692326, 6168.228971378578, 6301.590396624205, 6309.4952403806665, 6312.968435157794, 6314.526410519321, 6316.494680425463, 5816.912746308724, 5899.056151015817, 6035.064564992434, 6047.244094488189, 6047.304559975659, 6022.09668549721, 6110.980918142951, 6113.90744334296, 6115.045260690715, 6116.506790579029, 5925.200097986716, 6006.15913772081, 6017.638941663514, 6018.548608854266, 6018.789342372996, 5745.425457454221, 5791.9131213031, 5830.912536311874, 5998.970005149947, 6025.427584379477, 5926.822195334147, 5931.466168044896, 5934.495567876779, 5935.465808077293, 5957.046872500379, 5944.227250457586, 6030.758769649165, 6032.245969253843, 6034.155218269358, 6048.637081775108, 5685.061964775931, 5765.91351129725, 5858.2677165354335, 5916.7041647918295, 5986.431289027535], "unit": "ms", "name": "dojo.html", "value": 5602.075227494934}, {"lowerIsBetter": false, "replicates": [578.878435528539, 611.541343992006, 613.8434699151717, 614.0022463496817, 628.4249911331133, 34925.47611785822, 42615.78692106539, 43073.7846310768, 43129.56870431295, 43133.7843310783, 46440.0, 46975.29537056947, 47011.82470438232, 47056.764716176374, 47100.52899471004, 8115.715949941753, 8388.916110838874, 9254.583543740515, 9430.198433133191, 10724.195685323593, 10972.780544389107, 11037.061849742782, 11081.944590277079, 11091.445427728622, 11099.500522476468, 11048.834267485994, 11166.665000050012, 11174.944125279362, 11177.105831533498, 11181.105511559055, 10931.344119352825, 11008.119350451945, 11010.559577616887, 11028.393438360887, 11041.0, 10720.78558428835, 10819.67540973772, 10847.837282440769, 10848.457577121113, 10854.34873907568, 43054.56945430537, 43487.130257394674, 43607.34588981168, 43628.03673834685, 43645.0, 3603.4594810778594, 3606.8196590170387, 3723.94414083789, 3773.584905660375, 4276.273033584298, 32683.509747353808, 32830.507542386884, 32912.6708732912, 32960.0, 32980.50529242039, 2905.302727345443, 3007.6090108286003, 3019.8037127586636, 3064.6628870824193, 3163.4147682678777, 311920.0, 312728.0, 313354.0, 313451.4327428371, 313486.0, 58796.706016469645, 59787.0, 67511.66244168846, 73693.63153184307, 74905.62547187229, 68505.0, 68617.0, 68718.31281687219, 68724.3127568728, 68864.31135688678, 18752.81247187538, 18947.526311842037, 18961.715574266396, 18965.525861853563, 18974.0, 13353.66615834612, 13441.327933603476, 13442.126261792951, 13490.460381584626, 13615.455381784617, 21989.560208795734, 22130.778692213193, 22170.88914555449, 22207.888960555094, 22253.554928901656, 8131.38153521689, 8501.53241571707, 8513.489190648581, 8544.31645468361, 8607.751875977949, 1048.8007278617038, 1069.2194697870598, 1138.9430528473542, 1162.412981444382, 1166.1894982986744, 30438.84780576083, 30663.693363066082, 31112.533312000338, 31200.843995779876, 31459.213519662193, 1107.4352080438873, 1108.8613923259593, 1142.4516232208587, 1192.6958625550428, 1193.7493126443544, 14287.499937502138, 14575.056399608713, 14717.190554519604, 15043.398264069534, 15718.921405393128, 277940.0, 279182.0, 281099.0, 281538.0, 283435.0, 52878.0, 53133.46866531285, 53288.46711532835, 53303.46696533063, 53315.0, 1375.3329635126956, 1408.64783804049, 1460.773580095087, 1481.5481278210034, 1489.9180545070126, 28630.0, 28686.0, 28927.0, 28976.855115724287, 29513.409731805674, 46146.53853461422, 46474.76762616165, 46556.30165547452, 46577.53422465732, 46619.30071048869, 268310.0, 270608.0, 270991.0, 271873.0, 273048.0, 36062.0, 36748.81625591855, 36938.076548086516, 37006.814965926074, 37204.813975929945, 47391.763041184575, 48461.0, 48563.75718121528, 48894.51105489042, 48963.26555101605, 40170.39744403778, 40400.595994039686, 40841.59158408497, 41205.381919270636, 41809.37285940774, 39327.6067239324, 39939.40090898581, 40555.59444405636, 40664.0, 41071.0, 38834.41748373833, 39520.2095958085, 39787.40318895277, 39920.20159596734, 39925.60074399219, 34840.47739283862, 35146.82426587851, 35948.28103437969, 36004.4599331005, 36878.44682329821, 27327.316817079733, 27758.5836212461, 27787.0, 28186.577201341584, 29459.85270073636], "unit": "ms", "name": "ext.html", "value": 20199.660782984305}, {"lowerIsBetter": false, "replicates": [375.9342115129853, 380.7093951504399, 381.0587848015402, 383.14558534468136, 385.79552836996385, 16168.353265869351, 18076.36732714355, 18116.456506304814, 19434.805651943476, 19442.611147777054, 18673.62652746944, 18877.811221887776, 18997.0, 19291.228350865982, 19627.705584416246, 5765.1928729977835, 5907.970460147693, 5910.379410161936, 5914.0, 5916.822495325147, 4996.050750357421, 5105.719185444799, 5816.33112192097, 5896.174535565025, 5897.852553686149, 3790.9620903791024, 3865.8067096645195, 5027.472115427882, 5056.469070747574, 5058.215976523645, 5119.846404607868, 5144.794208231666, 5201.55786758126, 5223.503767142115, 5233.842984710445, 4438.755868427235, 4511.436070491189, 4514.796834142471, 4525.909481810345, 4561.019380833137, 15150.772738408932, 15308.311125999357, 15361.385544578203, 15448.536543903701, 15676.608084797972, 2266.048259730917, 2296.2651951375565, 2302.5970455170277, 2327.2320134355623, 2443.841150325223, 10992.945035274852, 11107.666769996913, 11152.721181970515, 11180.944095279552, 11343.886561134366, 1813.8186181381757, 1854.88870667759, 1864.263615871723, 1911.3788018893915, 1927.5662975830326, 66222.33777662161, 66566.00452979628, 67961.320386796, 69779.30220697829, 70042.29957700409, 7793.571353575494, 7797.376209903194, 7945.523268603787, 7970.043594768549, 7979.9601001994615, 9370.85943710845, 9384.577694003787, 9386.85919711205, 9393.530323483936, 9396.06039396055, 6286.779962701284, 6436.16329877113, 6452.677366131768, 6472.708728107246, 6476.481881449473, 6714.362135597208, 6743.730250789913, 6757.256701762803, 6765.188177418643, 6782.660866956632, 3617.981910090485, 3630.7640003399692, 3652.1052342175803, 3687.483752274684, 3687.8198976327853, 1245.6948047728365, 1246.0903540415547, 1248.1200753468784, 1271.5930902111236, 1292.3667402972476, 461.78296200785576, 473.8081077163685, 522.628933457242, 528.20241435432, 542.842575653064, 12428.62714118578, 12504.437300321508, 12733.426995785212, 12792.552260670831, 12855.421506032251, 496.77396784462985, 531.6809914051615, 536.0404875273296, 539.8380485854228, 549.9505044546011, 6920.619365934922, 7152.642367881585, 7277.16312624055, 7353.080864891888, 7375.852482950311, 607162.0, 616360.0, 632292.0, 632718.0, 639682.0, 75269.48383097032, 82674.58662706768, 82923.58538207391, 83044.58477707693, 83698.0, 560.8121279371456, 573.2576313673802, 575.2751533068364, 575.6114622629708, 576.0926540698417, 11499.137564682514, 11757.706057348634, 11960.700982475508, 11960.76078478443, 12210.450529726006, 38558.42162367511, 38778.41832372573, 39592.0, 39809.20381592294, 39979.60020399759, 52313.0, 52772.20841687454, 52976.73511632417, 53996.190057148386, 54466.72766636142, 7293.088363954505, 7395.556266624019, 7400.407967362706, 7401.962990185015, 7412.925870741224, 13091.0, 13106.082574219714, 13220.206787592775, 13420.0, 13427.462901483832, 11600.071994240609, 11627.941860290644, 11642.76714465723, 11645.359505226956, 11798.941005294919, 11275.943620281847, 11384.373859437479, 11410.429478525875, 11466.598669046543, 11637.243579167327, 11554.76890462203, 11658.88341116578, 11738.70653233676, 11852.58515951972, 11869.703257418634, 6538.019297105472, 7255.637218138966, 7440.330370266702, 7445.590492522747, 7471.589062601609, 11406.0, 11435.428228588371, 11490.88509114898, 11614.593489227836, 11675.299482031105], "unit": "ms", "name": "jquery.html", "value": 7695.142917824646}, {"lowerIsBetter": false, "replicates": [1475.675351422687, 1539.699758547083, 1651.8513333799956, 1719.5787032177118, 1887.6413481438517, 6458.903116453251, 6573.70418331175, 6592.406683398493, 7137.10786151731, 7253.455990800685, 7579.090509138911, 7780.06639203295, 7849.0, 7851.057873055227, 7854.136045035056, 2382.9046838126496, 2384.630382290739, 2410.2287268074224, 2413.0227257960555, 2414.4446777241155, 2105.768365479804, 2141.7001619773246, 2343.0744855781954, 2375.7505461926507, 2422.9636555451684, 2317.8261630377706, 2327.8719670418122, 2329.301209637102, 2330.5222429401933, 2331.079223706635, 2348.859068455898, 2363.988180059106, 2366.349253955159, 2366.3965688749345, 2368.6447032945025, 2330.242671131872, 2354.482013956927, 2355.387599224197, 2358.634411666177, 2359.4809141988735, 7891.21087891212, 7930.0880398753725, 7936.6825326986855, 7940.086890007666, 7951.20487951206, 4802.735849528274, 4835.564799168056, 4857.902841943177, 4860.8055677772845, 4863.8540843774745, 3077.0, 3077.5229839374933, 3110.0358888744336, 3112.813231206112, 3121.5317702344605, 995.3381001633904, 1006.0744115413763, 1020.8264595018865, 1022.5705203814416, 1063.7127975446663, 10463.424511651856, 10544.367337959748, 10561.78876422475, 10567.73580660482, 10570.682879513626, 4371.409859668904, 4374.278244089754, 4434.268345722984, 4439.556044395535, 4444.022315090675, 9083.45499270046, 9089.590968406437, 9102.544872756469, 9107.726768196964, 9108.225800806873, 4832.4684284728655, 4832.758362081881, 4835.6131509479155, 4835.637327200474, 4840.540148685869, 4370.060437006059, 4370.104128653618, 4371.606555410029, 4375.47494300686, 4378.1900348435, 3572.732045096628, 3798.3732684106812, 3852.8739751803523, 3875.3024455597697, 3890.9416358754643, 1142.126273400849, 1145.3414286785132, 1202.2666173634077, 1202.7053371923935, 1245.3275231375137, 1196.916215864881, 1242.142921384242, 1290.9741805163844, 1513.129950278572, 1514.0158896717003, 7511.8497630047095, 7751.1861254568585, 7752.922470775333, 7759.456838021397, 7761.0, 1201.3932963853372, 1222.407132540717, 1232.8335674683983, 1240.460399726122, 1244.4089057697522, 6049.5462840287155, 6097.603655762361, 6144.170536977451, 6161.6611086389785, 6165.10605962133, 9298.86051709225, 9498.905010949802, 9509.762255943519, 9512.38169518979, 9516.429014259167, 9298.163165315154, 9411.29415293842, 9432.292578056673, 9457.66898158575, 9458.668946586837, 7364.77905662831, 7489.063867016732, 7495.287947644965, 7496.475246732676, 7500.174980752113, 7232.312930271616, 7326.890096648661, 7356.926430735624, 7357.852842943218, 7358.889616655647, 8303.4602750821, 8360.958195208985, 8389.95805020971, 8410.074891761778, 8414.242718155396, 8492.0, 8627.051024387438, 8645.827083458422, 8646.78383040429, 8653.480791152551, 7157.534760240567, 7194.460415468965, 7198.928010719826, 7213.495055346285, 7224.422046236184, 7017.754378596725, 7196.280371962762, 7727.150013498398, 7789.221077892165, 7791.610419479117, 7331.521847715194, 7348.448866335153, 7384.704611815682, 7403.925960740324, 7450.8882366763455, 7169.892451613126, 7198.316159964795, 7211.38703210212, 7282.453815963718, 7295.963520182365, 7283.490155689051, 7293.0519032525435, 7296.416286697157, 7323.707051717872, 7349.522281051714, 6653.833654158491, 6798.388145066968, 6803.251642319242, 6816.2842901496615, 6817.5909445433435, 6875.931240687529, 6890.0, 6895.448364130757, 6917.688704008231, 6939.653017349011], "unit": "ms", "name": "mootools.html", "value": 4617.515865084202}, {"lowerIsBetter": false, "replicates": [359.1846508425875, 360.3549646133421, 362.13811129511777, 365.92132691471335, 366.88076375178076, 5787.13193021047, 5904.822855314342, 5906.0, 5937.881242375155, 5941.851453713659, 5725.856853578663, 5774.769009239625, 5827.912581311284, 5834.241548598687, 5834.474897259246, 4583.770811459431, 4585.931211031837, 4586.19741545229, 4588.724676519403, 4594.908101837961, 4578.679492435531, 4581.221192397301, 4585.839495617654, 4588.9541104589025, 4589.173948689235, 4495.551535277929, 4556.954430455703, 4601.769911504428, 4609.562091601292, 4640.814367425316, 4617.353570500132, 4622.514635963209, 4630.560096790817, 4631.444226692818, 4647.442306923174, 3957.2679054375035, 4118.794060297003, 4135.31767258405, 4137.420761093449, 4139.2135494256, 5485.0949593317055, 5527.55779537636, 5588.245586845765, 5596.048671725816, 5634.3802181759975, 5261.789528418859, 5276.736163191864, 5279.47205279469, 5279.498447647467, 5281.815136470205, 6139.815805525796, 6150.569460137792, 6151.569390142692, 6157.7229024694, 6158.876822463526, 4364.214441400547, 4412.073464572443, 4418.889527761799, 4424.734515929023, 4498.617617502517, 6333.493320534346, 6396.488280937514, 6400.615963042186, 6405.5516113872045, 6413.615183088983, 4517.774111294422, 4518.254488009442, 4523.298888672195, 4524.298733696217, 4524.434445694288, 4548.749818759934, 4574.862754117382, 4581.770911454414, 4588.449386073626, 4591.311303304531, 5138.691678499302, 5226.425093239741, 5230.006298803215, 5245.924585459971, 5250.816221432232, 6283.96858015707, 6288.434040936338, 6288.717007734663, 6289.55973081889, 6294.748210071546, 1660.825613310585, 1676.2791999440115, 1679.9580010499835, 1681.9992104697685, 1682.6718789835863, 1132.796096702585, 1133.0765426177638, 1134.1097238667605, 1136.9033632141195, 1163.6741712320563, 6073.5748497604745, 6073.939260607337, 6074.574779765374, 6076.848078798065, 6080.179175811209, 6434.067060276234, 6436.259830119502, 6440.388163124496, 6443.903341449882, 6452.870942581122, 5967.104934259895, 6055.788047418408, 6056.848578785565, 6062.939370606326, 6068.362821903725, 3551.2187318789825, 3568.232829941575, 3568.6074531801482, 3568.91077723059, 3570.9822700530226, 6403.871922561522, 6515.12045873801, 6516.674166291667, 6617.172853393326, 6639.43564796988, 6578.901316480256, 6592.967035164794, 6600.1089852869245, 6610.471162307004, 6630.6353150576215, 6643.435307998874, 6663.100481435041, 6664.0, 6667.633280169637, 6676.26561078281, 6368.554201205871, 6479.8056058318325, 6480.902786458301, 6483.740650374121, 6512.967435162794, 6416.262129855127, 6433.163688720437, 6435.742570297323, 6484.254310754323, 6487.124238227968, 6711.496637752286, 6715.664216789043, 6716.597004179569, 6720.563163394364, 6723.596584204964, 4517.390152329525, 4583.037562111994, 4587.7706114693465, 4593.0, 4593.678442508993, 6545.770898018546, 6551.50863685216, 6553.0170474429215, 6556.868862622816, 6570.8028759137305, 6384.968075159594, 6397.520185985976, 6405.8718825624155, 6407.615543067431, 6409.807705768834, 6279.645133075097, 6385.553011289165, 6396.200474940632, 6396.840078998062, 6400.71196796136, 6275.466585340298, 6276.152719382825, 6278.0, 6280.340564240872, 6286.339934307015, 6404.839879003062, 6407.359264073555, 6407.391297826699, 6411.839704007437, 6418.582792118497, 6181.598196117238, 6246.156768836149, 6262.906056409066, 6272.560920735505, 6274.749010039547], "unit": "ms", "name": "prototype.html", "value": 4791.973306942319}, {"lowerIsBetter": false, "replicates": [849.7535714642753, 934.2012579244749, 936.9531523423827, 949.7388218239979, 957.6456711016923, 11847.822282665757, 12280.078994075435, 12315.507379704812, 12524.937375313133, 12686.175398599085, 12711.42798574064, 13986.720265594708, 14094.64763380916, 14112.15327080376, 14128.293585320744, 755.939524838013, 896.3098414221046, 900.1133883125149, 900.2168113741054, 900.3922352412128, 896.2113340260562, 901.0, 901.9594118264661, 902.2331018634142, 903.0563061600643, 795.1253621016893, 796.1083586383259, 947.8957314695413, 948.5494390164686, 949.7340744591526, 923.0215971070686, 932.995335023324, 933.6545478173051, 934.1499235695485, 934.4766930518931, 690.92399836018, 692.2419950154573, 692.5221597097986, 693.3482526425145, 699.0248603198561, 13578.796318055238, 13831.377588008563, 13855.307234638227, 13857.722845543032, 13859.722805543934, 13010.349482525837, 13616.0, 14553.563393098224, 14565.198914059722, 14769.852301477062, 11623.302601843916, 11665.70835729105, 11689.357085360301, 11764.235324703866, 11857.88142118585, 3577.5528058992627, 3600.3879340512176, 3604.2611264690677, 3618.873339433107, 3630.9273814523826, 50642.24036639454, 51057.777601577785, 51187.488125118645, 51384.74307628475, 51656.74171629155, 8520.957395212985, 8592.226699597066, 8683.478991260545, 8751.56242187888, 8965.193132618097, 13845.861541384658, 13864.930675346559, 13872.306384680927, 13907.0, 13914.791278130837, 7499.550026998398, 7561.7731468056045, 7575.545467271982, 7587.734429295058, 7652.846943061108, 4629.282461218517, 4645.907081858344, 4685.250359942393, 4685.5782979532005, 4704.7412392318065, 3050.435669401168, 3070.738987186114, 3074.6003019607306, 3077.215310095956, 3077.784555081123, 478.1369627821815, 479.063430992409, 485.85667228167773, 492.2468623006734, 492.91374009548184, 689.2039694153261, 714.639107250835, 723.4284914917157, 740.7111226621622, 746.5595298773641, 9087.36388452802, 9363.157315841607, 9452.243820494345, 9695.0, 9885.159761420216, 562.3111688181945, 713.600383785082, 720.1825927572139, 765.1430397954216, 816.1919699497455, 4472.306792447176, 4649.5815376616265, 4770.332153498513, 4808.2478334495745, 4832.830850920272, 65105.674471628285, 65910.34089659042, 66481.6675916627, 66662.0, 66883.0, 58951.705241474374, 59649.40350596525, 59720.0, 59939.400605994255, 60907.08639370324, 53623.78257304574, 54007.18989215165, 54571.0, 54954.5858771574, 55271.72364138153, 9303.86044209351, 9395.107464790834, 9423.717288481357, 9467.431954082776, 9551.856722149312, 39929.60070399375, 40064.0, 40079.1984160321, 40803.59196408117, 41429.171416572106, 49344.506554935426, 52323.47676523186, 52401.0, 52987.20519192292, 53272.73363633312, 8046.476978996347, 8259.752207433787, 8511.489310641133, 8588.484690918565, 8704.216620504185, 13893.305334733424, 14039.50861719835, 14189.716205676035, 14394.856051439352, 14456.060356076821, 12206.633800985985, 12396.132270741322, 12408.56570020045, 12436.19164754288, 13006.089573729749, 12453.688657783629, 12620.368981550702, 12813.423395947018, 12899.871001289866, 12942.870571294543, 11589.362585057566, 11818.468168932246, 11864.584739534075, 12103.818442723543, 12139.817902731642, 9021.909780902108, 9703.417794932328, 9918.05778451046, 10295.639652612123, 10521.316114452537, 8994.459214351484, 9346.20557252641, 9549.952250238704, 9958.950205248928, 10054.19566434698], "unit": "ms", "name": "yui.html", "value": 6645.513110267496}], "name": "dromaeo_css", "value": 7099.589591878291}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 104.0, 109.0, 109.0, 110.0, 100.0, 109.0, 101.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.2}, {"lowerIsBetter": true, "replicates": [155.0, 120.0, 154.0, 159.0, 128.0, 147.0, 154.0, 125.0, 162.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.5}, {"lowerIsBetter": true, "replicates": [160.0, 158.0, 160.0, 160.0, 161.0, 161.0, 162.0, 162.0, 159.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 71.0, 147.0, 71.0, 71.0, 71.0, 70.0, 164.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 87.5}, {"lowerIsBetter": true, "replicates": [90.0, 89.0, 136.0, 88.0, 90.0, 89.0, 143.0, 90.0, 90.0, 139.0], "unit": "ms", "name": "audio-oscillator", "value": 104.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 147.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 64.0, 53.0, 55.0, 54.0, 55.0, 57.0, 52.0, 52.0], "unit": "ms", "name": "json-parse-financial", "value": 55.2}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 62.0, 64.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.1}, {"lowerIsBetter": true, "replicates": [86.0, 76.0, 77.0, 78.0, 77.0, 81.0, 78.0, 78.0, 77.0, 76.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.4}, {"lowerIsBetter": true, "replicates": [109.0, 107.0, 113.0, 116.0, 113.0, 110.0, 115.0, 113.0, 115.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.1}, {"lowerIsBetter": true, "replicates": [141.0, 132.0, 140.0, 133.0, 129.0, 136.0, 138.0, 147.0, 134.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.4}, {"lowerIsBetter": true, "replicates": [61.0, 62.0, 61.0, 60.0, 67.0, 60.0, 62.0, 62.0, 60.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.6}], "name": "kraken", "value": 1458.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.58337497917708], "unit": "score", "name": "Richards", "value": 211.91790600000007}, {"lowerIsBetter": false, "replicates": [170.01020061203673], "unit": "score", "name": "DeltaBlue", "value": 388.9060759999999}, {"lowerIsBetter": false, "replicates": [278.8622420524261], "unit": "score", "name": "Encrypt", "value": 954.525066}, {"lowerIsBetter": false, "replicates": [4985.074626865671], "unit": "score", "name": "Decrypt", "value": 53.39558982035927}, {"lowerIsBetter": false, "replicates": [935.4536950420954], "unit": "score", "name": "RayTrace", "value": 791.048241}, {"lowerIsBetter": false, "replicates": [563.3802816901408], "unit": "score", "name": "Earley", "value": 1182.9718249999999}, {"lowerIsBetter": false, "replicates": [10589.473684210527], "unit": "score", "name": "Boyer", "value": 62.93636679920471}, {"lowerIsBetter": false, "replicates": [23833.333333333332], "unit": "score", "name": "RegExp", "value": 38.22314685314689}, {"lowerIsBetter": false, "replicates": [536.7686527106817], "unit": "score", "name": "Splay", "value": 151.81773299999998}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23239.977710476458}]} 02:10:28 INFO - RETURN: dromaeo_css: 7099.59 02:10:28 INFO - RETURN: kraken: 1458.80 02:10:28 INFO - RETURN: v8_7: 23239.98 02:10:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23239.98"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1458.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7099.59"}}} 02:10:28 INFO - Return code: 0 02:10:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 02:10:28 INFO - # TBPL SUCCESS # 02:10:28 INFO - Running post-action listener: _resource_record_post_action 02:10:28 INFO - Running post-run listener: _resource_record_post_run 02:10:29 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 4096; Write bytes: 508416000; Read time: 16; Write time: 466968 02:10:29 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 245891072; Read time: 0; Write time: 333692 02:10:29 INFO - run-tests - Wall time: 1206s; CPU: 12.0%; Read bytes: 4096; Write bytes: 261705728; Read time: 16; Write time: 133264 02:10:29 INFO - Running post-run listener: _upload_blobber_files 02:10:29 INFO - Blob upload gear active. 02:10:29 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:10:29 INFO - Copying logs to upload dir... 02:10:29 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.586673 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2015-12-29 02:10:28.604310) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-29 02:10:28.607799) ========= 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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009961 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451373999/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-29 02:10:28.659726) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 02:10:28.660058) ========= 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=21869f6c619b95355032545b000001da-1451375669.21767-173998707 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004581 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 02:10:28.710535) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-29 02:10:28.710868) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-29 02:10:28.711181) ========= ========= Total master_lag: 0.25 =========