builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1452045089.08 results: success (0) buildid: 20160105153132 builduid: b09566cbd9d144dc9bf4de063cd5d60d revision: 557c92e190be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.076392) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.076917) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.077234) ========= 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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006362 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.100546) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.100862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.114062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.114418) ========= 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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004736 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.163349) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.163656) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.164031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.164322) ========= 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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-05 17:51:29-- 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% 39.5M=0s 2016-01-05 17:51:29 (39.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153227 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.330732) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.331144) ========= 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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022296 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.381575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:29.382015) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 557c92e190be --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 557c92e190be --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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-05 17:51:29,777 Setting DEBUG logging. 2016-01-05 17:51:29,777 attempt 1/10 2016-01-05 17:51:29,777 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/557c92e190be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-05 17:51:30,096 unpacking tar archive at: mozilla-beta-557c92e190be/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623410 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:30.018681) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:30.019190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:30.027260) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:30.027620) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 17:51:30.028112) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-01-05 17:51:30.028412) ========= /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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:51:30 INFO - MultiFileLogger online at 20160105 17:51:30 in /builds/slave/test-pgo 17:51:30 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 17:51:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 17:51:30 INFO - {'append_to_log': False, 17:51:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 17:51:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:51:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 17:51:30 INFO - 'branch': 'Mozilla-Beta', 17:51:30 INFO - 'buildbot_json_path': 'buildprops.json', 17:51:30 INFO - 'config_files': ('talos/linux_config.py',), 17:51:30 INFO - 'default_actions': ('clobber', 17:51:30 INFO - 'read-buildbot-config', 17:51:30 INFO - 'download-and-extract', 17:51:30 INFO - 'populate-webroot', 17:51:30 INFO - 'create-virtualenv', 17:51:30 INFO - 'install', 17:51:30 INFO - 'run-tests'), 17:51:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:51:30 INFO - 'download_minidump_stackwalk': True, 17:51:30 INFO - 'download_symbols': 'ondemand', 17:51:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:51:30 INFO - 'tooltool.py': '/tools/tooltool.py', 17:51:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:51:30 INFO - '/tools/misc-python/virtualenv.py')}, 17:51:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:51:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:51:30 INFO - 'installer_path': 'installer.exe', 17:51:30 INFO - 'log_level': 'info', 17:51:30 INFO - 'log_name': 'talos', 17:51:30 INFO - 'log_to_console': True, 17:51:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:51:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:51:30 INFO - 'opt_config_files': (), 17:51:30 INFO - 'pip_index': False, 17:51:30 INFO - 'sps_profile': False, 17:51:30 INFO - 'sps_profile_interval': 0, 17:51:30 INFO - 'suite': 'dromaeojs', 17:51:30 INFO - 'system_bits': '32', 17:51:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:51:30 INFO - 'title': 'talos-linux64-ix-037', 17:51:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:51:30 INFO - 'use_talos_json': True, 17:51:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 17:51:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:51:30 INFO - 'work_dir': 'build'} 17:51:30 INFO - ##### 17:51:30 INFO - ##### Running clobber step. 17:51:30 INFO - ##### 17:51:30 INFO - Running pre-action listener: _resource_record_pre_action 17:51:30 INFO - Running main action method: clobber 17:51:30 INFO - rmtree: /builds/slave/test-pgo/build 17:51:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 17:51:31 INFO - Running post-action listener: _resource_record_post_action 17:51:31 INFO - ##### 17:51:31 INFO - ##### Running read-buildbot-config step. 17:51:31 INFO - ##### 17:51:31 INFO - Running pre-action listener: _resource_record_pre_action 17:51:31 INFO - Running main action method: read_buildbot_config 17:51:31 INFO - Using buildbot properties: 17:51:31 INFO - { 17:51:31 INFO - "properties": { 17:51:31 INFO - "buildnumber": 66, 17:51:31 INFO - "product": "firefox", 17:51:31 INFO - "script_repo_revision": "production", 17:51:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:51:31 INFO - "repository": "", 17:51:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:51:31 INFO - "buildid": "20160105153132", 17:51:31 INFO - "slavename": "talos-linux64-ix-037", 17:51:31 INFO - "pgo_build": "True", 17:51:31 INFO - "basedir": "/builds/slave/test-pgo", 17:51:31 INFO - "project": "", 17:51:31 INFO - "platform": "ubuntu64_hw", 17:51:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:51:31 INFO - "slavebuilddir": "test", 17:51:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:51:31 INFO - "branch": "mozilla-beta", 17:51:31 INFO - "repo_path": "releases/mozilla-beta", 17:51:31 INFO - "stage_platform": "linux64-pgo", 17:51:31 INFO - "builduid": "b09566cbd9d144dc9bf4de063cd5d60d", 17:51:31 INFO - "revision": "557c92e190be" 17:51:31 INFO - }, 17:51:31 INFO - "sourcestamp": { 17:51:31 INFO - "repository": "", 17:51:31 INFO - "hasPatch": false, 17:51:31 INFO - "project": "", 17:51:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 17:51:31 INFO - "changes": [ 17:51:31 INFO - { 17:51:31 INFO - "category": null, 17:51:31 INFO - "files": [ 17:51:31 INFO - { 17:51:31 INFO - "url": null, 17:51:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.tar.bz2" 17:51:31 INFO - } 17:51:31 INFO - ], 17:51:31 INFO - "repository": "", 17:51:31 INFO - "rev": "557c92e190be", 17:51:31 INFO - "who": "sendchange", 17:51:31 INFO - "when": 1452045070, 17:51:31 INFO - "number": 7421392, 17:51:31 INFO - "comments": "Bug 1234054 - Expose notification interfaces to service workers_ disable on B2G and Android. r=ehsan a=bustage", 17:51:31 INFO - "project": "", 17:51:31 INFO - "at": "Tue 05 Jan 2016 17:51:10", 17:51:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 17:51:31 INFO - "revlink": "", 17:51:31 INFO - "properties": [ 17:51:31 INFO - [ 17:51:31 INFO - "buildid", 17:51:31 INFO - "20160105153132", 17:51:31 INFO - "Change" 17:51:31 INFO - ], 17:51:31 INFO - [ 17:51:31 INFO - "builduid", 17:51:31 INFO - "b09566cbd9d144dc9bf4de063cd5d60d", 17:51:31 INFO - "Change" 17:51:31 INFO - ], 17:51:31 INFO - [ 17:51:31 INFO - "pgo_build", 17:51:31 INFO - "True", 17:51:31 INFO - "Change" 17:51:31 INFO - ] 17:51:31 INFO - ], 17:51:31 INFO - "revision": "557c92e190be" 17:51:31 INFO - } 17:51:31 INFO - ], 17:51:31 INFO - "revision": "557c92e190be" 17:51:31 INFO - } 17:51:31 INFO - } 17:51:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.tar.bz2. 17:51:31 INFO - Running post-action listener: _resource_record_post_action 17:51:31 INFO - ##### 17:51:31 INFO - ##### Running download-and-extract step. 17:51:31 INFO - ##### 17:51:31 INFO - Running pre-action listener: _resource_record_pre_action 17:51:31 INFO - Running main action method: download_and_extract 17:51:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 17:51: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')]} 17:51:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/test_packages.json 17:51:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 17:51:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 17:51:31 INFO - Downloaded 1270 bytes. 17:51:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 17:51:31 INFO - Using the following test package requirements: 17:51:31 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 17:51:31 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:51:31 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:51:31 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:51:31 INFO - u'jsshell-linux-x86_64.zip'], 17:51:31 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:51:31 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:51:31 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 17:51:31 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:51:31 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 17:51:31 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:51:31 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 17:51:31 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:51:31 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:51:31 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 17:51:31 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:51:31 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:51:31 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:51: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')]} 17:51:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:51:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:34 INFO - Downloaded 22571811 bytes. 17:51:34 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 17:51:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:51:34 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 17:51:35 INFO - Return code: 0 17:51:35 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 17:51:35 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')]} 17:51:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:51:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:35 INFO - Downloaded 22571811 bytes. 17:51:35 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 17:51:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:51:35 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 17:51:36 INFO - Return code: 0 17:51:36 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')]} 17:51:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 17:51:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:40 INFO - Downloaded 11131970 bytes. 17:51:40 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 17:51:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 17:51:40 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 17:51:40 INFO - Return code: 0 17:51: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')]} 17:51:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.tar.bz2 17:51:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 17:51:46 INFO - Downloaded 56625887 bytes. 17:51:46 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.tar.bz2 17:51:46 INFO - mkdir: /builds/slave/test-pgo/properties 17:51:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 17:51:46 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 17:51:46 INFO - Contents: 17:51:46 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.tar.bz2 17:51:46 INFO - Running post-action listener: _resource_record_post_action 17:51:46 INFO - Running post-action listener: set_extra_try_arguments 17:51:46 INFO - ##### 17:51:46 INFO - ##### Running populate-webroot step. 17:51:46 INFO - ##### 17:51:46 INFO - Running pre-action listener: _resource_record_pre_action 17:51:46 INFO - Running main action method: populate_webroot 17:51:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:51:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:51:46 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 17:51:46 INFO - '../fennec_ids.txt'], 17:51:46 INFO - 'tests': ['tcheck2']}, 17:51:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:51:46 INFO - '--tppagecycles', 17:51:46 INFO - '7'], 17:51:46 INFO - 'tests': ['tsvgm']}}, 17:51:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:51:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:51:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:51:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:51:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:51:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:51:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'tests': ['damp', 'tps']}, 17:51:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['damp', 'tps']}, 17:51:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:51:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:51:46 INFO - 'other': {'tests': ['a11yr', 17:51:46 INFO - 'ts_paint', 17:51:46 INFO - 'tpaint', 17:51:46 INFO - 'sessionrestore', 17:51:46 INFO - 'sessionrestore_no_auto_restore']}, 17:51:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['a11yr', 17:51:46 INFO - 'ts_paint', 17:51:46 INFO - 'tpaint', 17:51:46 INFO - 'sessionrestore', 17:51:46 INFO - 'sessionrestore_no_auto_restore']}, 17:51:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['a11yr', 17:51:46 INFO - 'ts_paint', 17:51:46 INFO - 'tpaint', 17:51:46 INFO - 'sessionrestore', 17:51:46 INFO - 'sessionrestore_no_auto_restore']}, 17:51:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['a11yr', 17:51:46 INFO - 'ts_paint', 17:51:46 INFO - 'tpaint', 17:51:46 INFO - 'sessionrestore', 17:51:46 INFO - 'sessionrestore_no_auto_restore']}, 17:51:46 INFO - 'other_l64': {'tests': ['a11yr', 17:51:46 INFO - 'ts_paint', 17:51:46 INFO - 'tpaint', 17:51:46 INFO - 'sessionrestore', 17:51:46 INFO - 'sessionrestore_no_auto_restore']}, 17:51:46 INFO - 'other_nol64': {'tests': ['a11yr', 17:51:46 INFO - 'ts_paint', 17:51:46 INFO - 'tpaint', 17:51:46 INFO - 'sessionrestore', 17:51:46 INFO - 'sessionrestore_no_auto_restore']}, 17:51:46 INFO - 'svgr': {'tests': ['tsvgx', 17:51:46 INFO - 'tsvgr_opacity', 17:51:46 INFO - 'tart', 17:51:46 INFO - 'tscrollx', 17:51:46 INFO - 'cart']}, 17:51:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['tsvgx', 17:51:46 INFO - 'tsvgr_opacity', 17:51:46 INFO - 'tart', 17:51:46 INFO - 'tscrollx', 17:51:46 INFO - 'cart']}, 17:51:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'tests': ['tp5o']}, 17:51:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'talos_options': ['--e10s'], 17:51:46 INFO - 'tests': ['tp5o']}, 17:51:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'talos_options': ['--xperf_path', 17:51:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:51:46 INFO - 'tests': ['tp5n']}, 17:51:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:51:46 INFO - 'talos_options': ['--e10s', 17:51:46 INFO - '--xperf_path', 17:51:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:51:46 INFO - 'tests': ['tp5n']}}, 17:51:46 INFO - 'talos.zip': {'path': '', 17:51:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:51:46 INFO - Running post-action listener: _resource_record_post_action 17:51:46 INFO - ##### 17:51:46 INFO - ##### Running create-virtualenv step. 17:51:46 INFO - ##### 17:51:46 INFO - Running pre-action listener: _resource_record_pre_action 17:51:46 INFO - Running main action method: create_virtualenv 17:51:46 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 17:51:46 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 17:51:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 17:51:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:51:46 INFO - Using real prefix '/usr' 17:51:46 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 17:51:47 INFO - Installing distribute.............................................................................................................................................................................................done. 17:51:49 INFO - Installing pip.................done. 17:51:49 INFO - Return code: 0 17:51:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 17:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:49 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:51:49 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 17:51:49 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 17:51:49 INFO - Using env: {'DISPLAY': ':0', 17:51:49 INFO - 'HOME': '/home/cltbld', 17:51:49 INFO - 'LANG': 'en_US.UTF-8', 17:51:49 INFO - 'LANGUAGE': 'en_US:en', 17:51:49 INFO - 'LOGNAME': 'cltbld', 17:51:49 INFO - 'MAIL': '/var/mail/cltbld', 17:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:49 INFO - 'MOZ_NO_REMOTE': '1', 17:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:49 INFO - 'NO_EM_RESTART': '1', 17:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:51:49 INFO - 'PWD': '/builds/slave/test-pgo', 17:51:49 INFO - 'SHELL': '/bin/bash', 17:51:49 INFO - 'SHLVL': '1', 17:51:49 INFO - 'TERM': 'linux', 17:51:49 INFO - 'TMOUT': '86400', 17:51:49 INFO - 'USER': 'cltbld', 17:51:49 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:49 INFO - '_': '/tools/buildbot/bin/python'} 17:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:49 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 17:51:49 INFO - Cleaning up... 17:51:49 INFO - Return code: 0 17:51:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 17:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:49 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:51:49 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 17:51:49 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 17:51:49 INFO - Using env: {'DISPLAY': ':0', 17:51:49 INFO - 'HOME': '/home/cltbld', 17:51:49 INFO - 'LANG': 'en_US.UTF-8', 17:51:49 INFO - 'LANGUAGE': 'en_US:en', 17:51:49 INFO - 'LOGNAME': 'cltbld', 17:51:49 INFO - 'MAIL': '/var/mail/cltbld', 17:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:49 INFO - 'MOZ_NO_REMOTE': '1', 17:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:49 INFO - 'NO_EM_RESTART': '1', 17:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:51:49 INFO - 'PWD': '/builds/slave/test-pgo', 17:51:49 INFO - 'SHELL': '/bin/bash', 17:51:49 INFO - 'SHLVL': '1', 17:51:49 INFO - 'TERM': 'linux', 17:51:49 INFO - 'TMOUT': '86400', 17:51:49 INFO - 'USER': 'cltbld', 17:51:49 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:49 INFO - '_': '/tools/buildbot/bin/python'} 17:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:49 INFO - Downloading/unpacking psutil>=0.7.1 17:51:49 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 17:51:49 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 17:51:49 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 17:51:49 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 17:51:49 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 17:51:49 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 17:51:51 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 17:51:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:51:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 17:51:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:51:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:51:51 INFO - Installing collected packages: psutil 17:51:51 INFO - Running setup.py install for psutil 17:51:51 INFO - building 'psutil._psutil_linux' extension 17:51:51 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 17:51:51 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 17:51:51 INFO - building 'psutil._psutil_posix' extension 17:51:51 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 17:51:51 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 17:51:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:51:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:51:52 INFO - Successfully installed psutil 17:51:52 INFO - Cleaning up... 17:51:52 INFO - Return code: 0 17:51:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 17:51: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')]} 17:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51: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')]} 17:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51: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', '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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:51: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 17:51: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 mozsystemmonitor==0.0.0 17:51:52 INFO - Using env: {'DISPLAY': ':0', 17:51:52 INFO - 'HOME': '/home/cltbld', 17:51:52 INFO - 'LANG': 'en_US.UTF-8', 17:51:52 INFO - 'LANGUAGE': 'en_US:en', 17:51:52 INFO - 'LOGNAME': 'cltbld', 17:51:52 INFO - 'MAIL': '/var/mail/cltbld', 17:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:52 INFO - 'MOZ_NO_REMOTE': '1', 17:51:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:52 INFO - 'NO_EM_RESTART': '1', 17:51:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:51:52 INFO - 'PWD': '/builds/slave/test-pgo', 17:51:52 INFO - 'SHELL': '/bin/bash', 17:51:52 INFO - 'SHLVL': '1', 17:51:52 INFO - 'TERM': 'linux', 17:51:52 INFO - 'TMOUT': '86400', 17:51:52 INFO - 'USER': 'cltbld', 17:51:52 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:52 INFO - '_': '/tools/buildbot/bin/python'} 17:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:51: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 17:51: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 17:51: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 17:51: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 17:51: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 17:51: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 17:51:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:51:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:51:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:51:54 INFO - Installing collected packages: mozsystemmonitor 17:51:54 INFO - Running setup.py install for mozsystemmonitor 17:51:54 INFO - Successfully installed mozsystemmonitor 17:51:54 INFO - Cleaning up... 17:51:54 INFO - Return code: 0 17:51:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 17:51:54 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')]} 17:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:54 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')]} 17:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:54 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:51:54 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 17:51:54 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 17:51:54 INFO - Using env: {'DISPLAY': ':0', 17:51:54 INFO - 'HOME': '/home/cltbld', 17:51:54 INFO - 'LANG': 'en_US.UTF-8', 17:51:54 INFO - 'LANGUAGE': 'en_US:en', 17:51:54 INFO - 'LOGNAME': 'cltbld', 17:51:54 INFO - 'MAIL': '/var/mail/cltbld', 17:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:54 INFO - 'MOZ_NO_REMOTE': '1', 17:51:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:54 INFO - 'NO_EM_RESTART': '1', 17:51:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:51:54 INFO - 'PWD': '/builds/slave/test-pgo', 17:51:54 INFO - 'SHELL': '/bin/bash', 17:51:54 INFO - 'SHLVL': '1', 17:51:54 INFO - 'TERM': 'linux', 17:51:54 INFO - 'TMOUT': '86400', 17:51:54 INFO - 'USER': 'cltbld', 17:51:54 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:54 INFO - '_': '/tools/buildbot/bin/python'} 17:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:54 INFO - Downloading/unpacking blobuploader==1.2.4 17:51: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 17:51: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 17:51: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 17:51: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 17:51: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 17:51: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 17:51:56 INFO - Downloading blobuploader-1.2.4.tar.gz 17:51:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:51:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:51:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:51:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 17:51:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 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 17:51:56 INFO - Downloading docopt-0.6.1.tar.gz 17:51:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:51:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 17:51:56 INFO - Installing collected packages: blobuploader, requests, docopt 17:51:56 INFO - Running setup.py install for blobuploader 17:51:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:51:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 17:51:56 INFO - Running setup.py install for requests 17:51:57 INFO - Running setup.py install for docopt 17:51:57 INFO - Successfully installed blobuploader requests docopt 17:51:57 INFO - Cleaning up... 17:51:57 INFO - Return code: 0 17:51:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:51:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:51:57 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:51:57 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 17:51:57 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 17:51:57 INFO - Using env: {'DISPLAY': ':0', 17:51:57 INFO - 'HOME': '/home/cltbld', 17:51:57 INFO - 'LANG': 'en_US.UTF-8', 17:51:57 INFO - 'LANGUAGE': 'en_US:en', 17:51:57 INFO - 'LOGNAME': 'cltbld', 17:51:57 INFO - 'MAIL': '/var/mail/cltbld', 17:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:51:57 INFO - 'MOZ_NO_REMOTE': '1', 17:51:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:51:57 INFO - 'NO_EM_RESTART': '1', 17:51:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:51:57 INFO - 'PWD': '/builds/slave/test-pgo', 17:51:57 INFO - 'SHELL': '/bin/bash', 17:51:57 INFO - 'SHLVL': '1', 17:51:57 INFO - 'TERM': 'linux', 17:51:57 INFO - 'TMOUT': '86400', 17:51:57 INFO - 'USER': 'cltbld', 17:51:57 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:51:57 INFO - '_': '/tools/buildbot/bin/python'} 17:51:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:51:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:51:57 INFO - Running setup.py (path:/tmp/pip-2j4AfB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:51:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:51:57 INFO - Running setup.py (path:/tmp/pip-FA0wiQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:51:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:51:57 INFO - Running setup.py (path:/tmp/pip-2IAyZa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:51:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:51:57 INFO - Running setup.py (path:/tmp/pip-tZjS03-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:51:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:51:57 INFO - Running setup.py (path:/tmp/pip-Uz26he-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:51:58 INFO - Running setup.py (path:/tmp/pip-PNmmoX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:51:58 INFO - Running setup.py (path:/tmp/pip-fLqJXT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:51:58 INFO - Running setup.py (path:/tmp/pip-ArYUb4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:51:58 INFO - Running setup.py (path:/tmp/pip-QH_vhv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:51:58 INFO - Running setup.py (path:/tmp/pip-TCl_CT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:51:58 INFO - Running setup.py (path:/tmp/pip-TQ9Fhj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:51:58 INFO - Running setup.py (path:/tmp/pip-gkhXIr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:51:58 INFO - Running setup.py (path:/tmp/pip-oPQ_Nq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:51:58 INFO - Running setup.py (path:/tmp/pip-VWiO8U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:51:58 INFO - Running setup.py (path:/tmp/pip-yuPyuk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:51:58 INFO - Running setup.py (path:/tmp/pip-ThqhPg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:51:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:51:58 INFO - Running setup.py (path:/tmp/pip-lFbnnA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:51:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:51:58 INFO - Running setup.py install for manifestparser 17:51:58 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 17:51:58 INFO - Running setup.py install for mozcrash 17:51:59 INFO - Running setup.py install for mozdebug 17:51:59 INFO - Running setup.py install for mozdevice 17:51:59 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Running setup.py install for mozfile 17:51:59 INFO - Running setup.py install for mozhttpd 17:51:59 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Running setup.py install for mozinfo 17:51:59 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Running setup.py install for mozInstall 17:51:59 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Running setup.py install for mozleak 17:51:59 INFO - Running setup.py install for mozlog 17:51:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Running setup.py install for moznetwork 17:51:59 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 17:51:59 INFO - Running setup.py install for mozprocess 17:52:00 INFO - Running setup.py install for mozprofile 17:52:00 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 17:52:00 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 17:52:00 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 17:52:00 INFO - Running setup.py install for mozrunner 17:52:00 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 17:52:00 INFO - Running setup.py install for mozscreenshot 17:52:00 INFO - Running setup.py install for moztest 17:52:00 INFO - Running setup.py install for mozversion 17:52:00 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 17:52:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:52:00 INFO - Cleaning up... 17:52:00 INFO - Return code: 0 17:52:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:00 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:52:00 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 17:52:00 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 17:52:00 INFO - Using env: {'DISPLAY': ':0', 17:52:00 INFO - 'HOME': '/home/cltbld', 17:52:00 INFO - 'LANG': 'en_US.UTF-8', 17:52:00 INFO - 'LANGUAGE': 'en_US:en', 17:52:00 INFO - 'LOGNAME': 'cltbld', 17:52:00 INFO - 'MAIL': '/var/mail/cltbld', 17:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:00 INFO - 'MOZ_NO_REMOTE': '1', 17:52:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:00 INFO - 'NO_EM_RESTART': '1', 17:52:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:52:00 INFO - 'PWD': '/builds/slave/test-pgo', 17:52:00 INFO - 'SHELL': '/bin/bash', 17:52:00 INFO - 'SHLVL': '1', 17:52:00 INFO - 'TERM': 'linux', 17:52:00 INFO - 'TMOUT': '86400', 17:52:00 INFO - 'USER': 'cltbld', 17:52:00 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:00 INFO - '_': '/tools/buildbot/bin/python'} 17:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:52:00 INFO - Running setup.py (path:/tmp/pip-2qkigr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:52:00 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)) 17:52:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:52:00 INFO - Running setup.py (path:/tmp/pip-6jQETK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:52:01 INFO - Running setup.py (path:/tmp/pip-xyizqT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:52:01 INFO - Running setup.py (path:/tmp/pip-nR_RRy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:52:01 INFO - Running setup.py (path:/tmp/pip-anzox0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:52:01 INFO - Running setup.py (path:/tmp/pip-AKqV57-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:52:01 INFO - Running setup.py (path:/tmp/pip-us0uzh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:52:01 INFO - Running setup.py (path:/tmp/pip-eeERFW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:52:01 INFO - Running setup.py (path:/tmp/pip-SDbtZl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:52:01 INFO - Running setup.py (path:/tmp/pip-TDkWZn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:52:01 INFO - Running setup.py (path:/tmp/pip-sjafze-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:52:01 INFO - Running setup.py (path:/tmp/pip-F4kt6_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:52:01 INFO - Running setup.py (path:/tmp/pip-o_kTh8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:52:01 INFO - Running setup.py (path:/tmp/pip-Czb7qK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:52:01 INFO - Running setup.py (path:/tmp/pip-kvNheT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:52:01 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)) 17:52:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:52:01 INFO - Running setup.py (path:/tmp/pip-QsUXtd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:52:02 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)) 17:52:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:52:02 INFO - Running setup.py (path:/tmp/pip-Y82fdM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:52:02 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)) 17:52:02 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)) 17:52: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.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 17:52:02 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)) 17:52: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 mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:52:02 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)) 17:52:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 17:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:52:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:52:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:52:03 INFO - Downloading blessings-1.5.1.tar.gz 17:52:03 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 17:52:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 17:52:03 INFO - Installing collected packages: blessings 17:52:03 INFO - Running setup.py install for blessings 17:52:03 INFO - Successfully installed blessings 17:52:03 INFO - Cleaning up... 17:52:04 INFO - Return code: 0 17:52:04 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 17:52:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:52:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:52:04 INFO - Reading from file tmpfile_stdout 17:52:04 INFO - Current package versions: 17:52:04 INFO - argparse == 1.2.1 17:52:04 INFO - blessings == 1.5.1 17:52:04 INFO - blobuploader == 1.2.4 17:52:04 INFO - docopt == 0.6.1 17:52:04 INFO - manifestparser == 1.1 17:52:04 INFO - mozInstall == 1.12 17:52:04 INFO - mozcrash == 0.16 17:52:04 INFO - mozdebug == 0.1 17:52:04 INFO - mozdevice == 0.46 17:52:04 INFO - mozfile == 1.2 17:52:04 INFO - mozhttpd == 0.7 17:52:04 INFO - mozinfo == 0.8 17:52:04 INFO - mozleak == 0.1 17:52:04 INFO - mozlog == 3.0 17:52:04 INFO - moznetwork == 0.27 17:52:04 INFO - mozprocess == 0.22 17:52:04 INFO - mozprofile == 0.27 17:52:04 INFO - mozrunner == 6.11 17:52:04 INFO - mozscreenshot == 0.1 17:52:04 INFO - mozsystemmonitor == 0.0 17:52:04 INFO - moztest == 0.7 17:52:04 INFO - mozversion == 1.4 17:52:04 INFO - psutil == 3.1.1 17:52:04 INFO - requests == 1.2.3 17:52:04 INFO - wsgiref == 0.1.2 17:52:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:52:04 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')]} 17:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:04 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')]} 17:52:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:04 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:52:04 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 17:52:04 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 17:52:04 INFO - Using env: {'DISPLAY': ':0', 17:52:04 INFO - 'HOME': '/home/cltbld', 17:52:04 INFO - 'LANG': 'en_US.UTF-8', 17:52:04 INFO - 'LANGUAGE': 'en_US:en', 17:52:04 INFO - 'LOGNAME': 'cltbld', 17:52:04 INFO - 'MAIL': '/var/mail/cltbld', 17:52:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:04 INFO - 'MOZ_NO_REMOTE': '1', 17:52:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:04 INFO - 'NO_EM_RESTART': '1', 17:52:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:52:04 INFO - 'PWD': '/builds/slave/test-pgo', 17:52:04 INFO - 'SHELL': '/bin/bash', 17:52:04 INFO - 'SHLVL': '1', 17:52:04 INFO - 'TERM': 'linux', 17:52:04 INFO - 'TMOUT': '86400', 17:52:04 INFO - 'USER': 'cltbld', 17:52:04 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:52:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:04 INFO - '_': '/tools/buildbot/bin/python'} 17:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 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)) 17:52:04 INFO - Cleaning up... 17:52:04 INFO - Return code: 0 17:52:04 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 17:52:04 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')]} 17:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:04 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')]} 17:52:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:04 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:52:04 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 17:52:04 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 17:52:04 INFO - Using env: {'DISPLAY': ':0', 17:52:04 INFO - 'HOME': '/home/cltbld', 17:52:04 INFO - 'LANG': 'en_US.UTF-8', 17:52:04 INFO - 'LANGUAGE': 'en_US:en', 17:52:04 INFO - 'LOGNAME': 'cltbld', 17:52:04 INFO - 'MAIL': '/var/mail/cltbld', 17:52:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:04 INFO - 'MOZ_NO_REMOTE': '1', 17:52:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:04 INFO - 'NO_EM_RESTART': '1', 17:52:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:52:04 INFO - 'PWD': '/builds/slave/test-pgo', 17:52:04 INFO - 'SHELL': '/bin/bash', 17:52:04 INFO - 'SHLVL': '1', 17:52:04 INFO - 'TERM': 'linux', 17:52:04 INFO - 'TMOUT': '86400', 17:52:04 INFO - 'USER': 'cltbld', 17:52:04 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:52:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:04 INFO - '_': '/tools/buildbot/bin/python'} 17:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:04 INFO - Downloading/unpacking jsonschema 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:52:06 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 17:52:06 INFO - Downloading/unpacking functools32 (from jsonschema) 17:52:06 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 17:52:06 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 17:52:06 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 17:52:06 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 17:52:06 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 17:52:06 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 17:52:06 INFO - Downloading functools32-3.2.3-2.tar.gz 17:52:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 17:52:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 17:52:06 INFO - warning: no files found matching '*.txt' 17:52:06 INFO - no previously-included directories found matching 'build' 17:52:06 INFO - no previously-included directories found matching 'dist' 17:52:06 INFO - no previously-included directories found matching '.git*' 17:52:06 INFO - Installing collected packages: jsonschema, functools32 17:52:06 INFO - Running setup.py install for functools32 17:52:06 INFO - warning: no files found matching '*.txt' 17:52:06 INFO - no previously-included directories found matching 'build' 17:52:06 INFO - no previously-included directories found matching 'dist' 17:52:06 INFO - no previously-included directories found matching '.git*' 17:52:06 INFO - Successfully installed jsonschema functools32 17:52:06 INFO - Cleaning up... 17:52:06 INFO - Return code: 0 17:52:06 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 17:52:06 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')]} 17:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:06 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')]} 17:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:52:06 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 0x7fb246fcdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28c3600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', '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 17:52:06 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 17:52:06 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 17:52:06 INFO - Using env: {'DISPLAY': ':0', 17:52:06 INFO - 'HOME': '/home/cltbld', 17:52:06 INFO - 'LANG': 'en_US.UTF-8', 17:52:06 INFO - 'LANGUAGE': 'en_US:en', 17:52:06 INFO - 'LOGNAME': 'cltbld', 17:52:06 INFO - 'MAIL': '/var/mail/cltbld', 17:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:06 INFO - 'MOZ_NO_REMOTE': '1', 17:52:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:06 INFO - 'NO_EM_RESTART': '1', 17:52:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:52:06 INFO - 'PWD': '/builds/slave/test-pgo', 17:52:06 INFO - 'SHELL': '/bin/bash', 17:52:06 INFO - 'SHLVL': '1', 17:52:06 INFO - 'TERM': 'linux', 17:52:06 INFO - 'TMOUT': '86400', 17:52:06 INFO - 'USER': 'cltbld', 17:52:06 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:06 INFO - '_': '/tools/buildbot/bin/python'} 17:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:52:07 INFO - Downloading/unpacking flake8 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52: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 17:52:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:52:08 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 17:52:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:52:08 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 17:52:08 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 17:52:08 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 17:52:08 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 17:52:08 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 17:52:08 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 17:52:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:52:09 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 17:52:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 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 17:52:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 17:52:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 17:52:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:52:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:52:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:52:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:52:09 INFO - no previously-included directories found matching 'docs/_build' 17:52:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:52:09 INFO - Running setup.py install for pep8 17:52:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:52:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:52:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:52:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:52:09 INFO - no previously-included directories found matching 'docs/_build' 17:52:09 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 17:52:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:52:09 INFO - Cleaning up... 17:52:10 INFO - Return code: 0 17:52:10 INFO - Running post-action listener: _resource_record_post_action 17:52:10 INFO - Running post-action listener: _start_resource_monitoring 17:52:10 INFO - Starting resource monitoring. 17:52:10 INFO - ##### 17:52:10 INFO - ##### Running install step. 17:52:10 INFO - ##### 17:52:10 INFO - Running pre-action listener: _resource_record_pre_action 17:52:10 INFO - Running main action method: install 17:52:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:52:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:52:10 INFO - Reading from file tmpfile_stdout 17:52:10 INFO - Detecting whether we're running mozinstall >=1.0... 17:52:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 17:52:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 17:52:10 INFO - Reading from file tmpfile_stdout 17:52:10 INFO - Output received: 17:52:10 INFO - Usage: mozinstall [options] installer 17:52:10 INFO - Options: 17:52:10 INFO - -h, --help show this help message and exit 17:52:10 INFO - -d DEST, --destination=DEST 17:52:10 INFO - Directory to install application into. [default: 17:52:10 INFO - "/builds/slave/test-pgo"] 17:52:10 INFO - --app=APP Application being installed. [default: firefox] 17:52:10 INFO - mkdir: /builds/slave/test-pgo/build/application 17:52:10 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'] 17:52:10 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 17:52:24 INFO - Reading from file tmpfile_stdout 17:52:24 INFO - Output received: 17:52:24 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 17:52:24 INFO - Running post-action listener: _resource_record_post_action 17:52:24 INFO - ##### 17:52:24 INFO - ##### Running run-tests step. 17:52:24 INFO - ##### 17:52:24 INFO - Running pre-action listener: _resource_record_pre_action 17:52:24 INFO - Running main action method: run_tests 17:52:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 17:52:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 17:52:24 INFO - Python 2.7.3 17:52:24 INFO - Return code: 0 17:52:24 INFO - grabbing minidump binary from tooltool 17:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:52:24 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 0x2952350>, '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 0x2953250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29536e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 17:52:24 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 17:52:24 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 17:52:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:52:25 INFO - Return code: 0 17:52:25 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 17:52:25 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 17:52:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 17:52:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 17:52:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 17:52:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 17:52:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 17:52:25 INFO - Return code: 0 17:52:25 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/1452036692/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 17:52:25 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/1452036692/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost 17:52:25 INFO - Using env: {'DISPLAY': ':0', 17:52:25 INFO - 'HOME': '/home/cltbld', 17:52:25 INFO - 'LANG': 'en_US.UTF-8', 17:52:25 INFO - 'LANGUAGE': 'en_US:en', 17:52:25 INFO - 'LOGNAME': 'cltbld', 17:52:25 INFO - 'MAIL': '/var/mail/cltbld', 17:52:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:52:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 17:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:52:25 INFO - 'MOZ_NO_REMOTE': '1', 17:52:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:52:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:52:25 INFO - 'NO_EM_RESTART': '1', 17:52:25 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:52:25 INFO - 'PWD': '/builds/slave/test-pgo', 17:52:25 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 17:52:25 INFO - 'SHELL': '/bin/bash', 17:52:25 INFO - 'SHLVL': '1', 17:52:25 INFO - 'TERM': 'linux', 17:52:25 INFO - 'TMOUT': '86400', 17:52:25 INFO - 'USER': 'cltbld', 17:52:25 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164', 17:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:52:25 INFO - '_': '/tools/buildbot/bin/python'} 17:52:25 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/1452036692/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost'] with output_timeout 3600 17:52:26 INFO - mozversion INFO | application_buildid: 20160105153132 17:52:26 INFO - mozversion INFO | application_changeset: 557c92e190be9e8c85486d3635a4d391c55b2621 17:52:26 INFO - mozversion INFO | application_display_name: Nightly 17:52:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:52:26 INFO - mozversion INFO | application_name: Firefox 17:52:26 INFO - mozversion INFO | application_remotingname: firefox 17:52:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:52:26 INFO - mozversion INFO | application_vendor: Mozilla 17:52:26 INFO - mozversion INFO | application_version: 44.0 17:52:26 INFO - mozversion INFO | platform_buildid: 20160105153132 17:52:26 INFO - mozversion INFO | platform_changeset: 557c92e190be9e8c85486d3635a4d391c55b2621 17:52:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:52:26 INFO - mozversion INFO | platform_version: 44.0 17:52:26 INFO - 2016-01-05 17:52:26,169 DEBUG : using testdate: 1452045146 17:52:26 INFO - 2016-01-05 17:52:26,169 DEBUG : actual date: 1452045146 17:52:26 INFO - 2016-01-05 17:52:26,178 INFO : starting webserver on 'localhost:58906' 17:52:26 INFO - 2016-01-05 17:52:26,179 INFO : Starting test suite talos-linux64-ix-037 17:52:26 INFO - 2016-01-05 17:52:26,179 INFO : Starting test dromaeo_css 17:52:26 INFO - 2016-01-05 17:52:26,179 DEBUG : operating with platform_type : linux_ 17:52:26 INFO - 2016-01-05 17:52:26,180 INFO : Initialising browser for dromaeo_css test... 17:52:26 INFO - 2016-01-05 17:52:26,194 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBpxKBk/profile http://localhost:58906/getInfo.html 17:52:29 INFO - 2016-01-05 17:52:29,855 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:52:29 INFO - 2016-01-05 17:52:29,856 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:52:29 INFO - 2016-01-05 17:52:29,856 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:52:29 INFO - 2016-01-05 17:52:29,856 DEBUG : BROWSER_OUTPUT: __metrics 17:52:30 INFO - 2016-01-05 17:52:30,001 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] 17:52:31 INFO - 2016-01-05 17:52:31,252 INFO : Browser initialized. 17:52:31 INFO - 2016-01-05 17:52:31,253 INFO : Running cycle 1/1 for dromaeo_css test... 17:52:31 INFO - 2016-01-05 17:52:31,253 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBpxKBk/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:52:42 INFO - 2016-01-05 17:52:42,839 DEBUG : BROWSER_OUTPUT: RSS: Main: 129056768 17:52:42 INFO - 2016-01-05 17:52:42,840 DEBUG : BROWSER_OUTPUT: 17:53:33 INFO - 2016-01-05 17:53:33,629 DEBUG : BROWSER_OUTPUT: 1452045213621 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] 17:55:41 INFO - 2016-01-05 17:55:41,656 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58906/tests/dromaeo/cssquery-ext.html) 17:55:42 INFO - 2016-01-05 17:55:42,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 209911808 17:55:42 INFO - 2016-01-05 17:55:42,640 DEBUG : BROWSER_OUTPUT: 17:58:44 INFO - 2016-01-05 17:58:44,759 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/dromaeo/cssquery-ext.html (next: http://localhost:58906/tests/dromaeo/cssquery-jquery.html) 17:58:45 INFO - 2016-01-05 17:58:45,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 216756224 17:58:45 INFO - 2016-01-05 17:58:45,320 DEBUG : BROWSER_OUTPUT: 18:01:46 INFO - 2016-01-05 18:01:46,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58906/tests/dromaeo/cssquery-mootools.html) 18:01:47 INFO - 2016-01-05 18:01:47,556 DEBUG : BROWSER_OUTPUT: RSS: Main: 214663168 18:01:47 INFO - 2016-01-05 18:01:47,556 DEBUG : BROWSER_OUTPUT: 18:04:49 INFO - 2016-01-05 18:04:49,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58906/tests/dromaeo/cssquery-prototype.html) 18:04:49 INFO - 2016-01-05 18:04:49,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 194555904 18:04:49 INFO - 2016-01-05 18:04:49,802 DEBUG : BROWSER_OUTPUT: 18:07:52 INFO - 2016-01-05 18:07:52,157 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58906/tests/dromaeo/cssquery-yui.html) 18:07:52 INFO - 2016-01-05 18:07:52,719 DEBUG : BROWSER_OUTPUT: RSS: Main: 195682304 18:07:52 INFO - 2016-01-05 18:07:52,719 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/dromaeo/cssquery-yui.html (next: http://localhost:58906/tests/dromaeo/cssquery-dojo.html) 18:10:54 INFO - 2016-01-05 18:10:54,907 DEBUG : BROWSER_OUTPUT: RSS: Main: 235028480 18:10:54 INFO - 2016-01-05 18:10:54,908 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,908 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:10:54 INFO - 2016-01-05 18:10:54,908 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:10:54 INFO - 2016-01-05 18:10:54,908 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:10:54 INFO - 2016-01-05 18:10:54,908 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:10:54 INFO - 2016-01-05 18:10:54,909 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3315.5855518060243;3371.426857434236;3383.374075795978;3383.8308084595765;3409.897703068907;5626.803061892834;5633.8309850704445;5696.287964004499;5701.287339082614;5712.857178570538;5728.083506638938;5735.340435849878;5753.510951569122;5760.45275698809;5760.510356619692;5040.84877453677;5187.507186817255;5221.791128354861;5292.814751483699;5297.073012222845;5224.059669259532;5252.054630166568;5261.894762104775;5278.656887302313;5279.5512381447625;5102.489751024905;5145;5171.7672704728375;5209;5210.322658054448;5224.817131400383;5229;5252.658577192489;5261.4475480074425;5263.605229607795;5194.37667479901;5216.061109000379;5219.399769026572;5278.2082687596785;5292.259083728281;5315.229291752674;5338.546223571001;5421.728913554307;5433.212184233264;5457.863553411157;6115.908261376083;6130.356312587203;6149.29283132441;6164.106204600353;6169.629822210637;5999.250093738283;6175.320714721377;6203.596766210182;6207.4723648489935;6215.906761398583;5632.85917852057;5726.112452569859;5730.598858079936;5731.713414329267;5751.137329400582;6233.2208473940755;6261.622443062519;6284.560080794392;6301.023341382093;6304.590201636879;5671.007573674673;5758.654480731169;6087.969560152171;6093.512518998469;6096.268447786294;6050.515958723292;6127.705454835918;6132.308783780411;6138.539609529304;6141.608749743955;6124.958757011319;6125.592594254237;6128.787424850721;6131.071888765667;6134.263888333428;5998.1602575639445;6121.693915304217;6121.7474480264555;6136.079588061737;6137.508999280047;4570.97714511432;4634.536546345338;4637.234856248681;4650.67445278834;4678.11115887987;4130.938035929464;4133.132042271157;4139.9793001035405;4150.481189851269;4160.667146628323;5586.35756887955;5688.772449102073;5826.796062137805;5831.7375718091935;5833.649981001154;6040.395960403925;6081.6351018939;6100.542459315569;6102.786402475892;6113.083037544315;5756.107803290497;5833.854153646108;5843.970780146157;5850.385709500526;5858.2677165354335;5193.039287731782;5237.5286224239235;5267.288915996368;5283.973580132151;5287.762050707727;6313.684315784192;6333.46165575922;6338.492920566344;6338.524610654219;6338.587991780611;6169.5064394848305;6183.783567575113;6205.100260462298;6213.1922850029205;6214.875702486015;6156.415140561639;6190.969045154746;6292.465140463112;6301.905471418024;6333.809985700436;6059.969700151471;6061.484773794277;6061.75506518259;6083.148359229623;6092.8781424372155;6149.477294430024;6149.84625384369;6152.200213972155;6153.169322141453;6178.505719542516;5783.103618939072;5900.586958912835;5948.226730525004;6062.818115456544;6076.817695469143;5970.044792833126;6003.369646187088;6006.039033754579;6020.909686354796;6052.122442245938;5601.663900166003;5893.351731309638;5894.322152952465;5899.823005309848;5900.64596124234;5891.469767720754;5897.498712609477;6013;6032.758689652364;6043.486303664238;5897.351291357862;5938.138969849261;5940.257467816523;5941.22764040672;5985.521158307412 18:10:54 INFO - 2016-01-05 18:10:54,913 DEBUG : BROWSER_OUTPUT: |1;ext.html;640.5292110298932;641.0448431836563;645.6836150286359;649.042662073442;657.2146285189201;42420.787896060516;42953.57046429535;42968.35547466783;42980;42994.35508467371;47326.52673473273;47861.7606911965;47915.76042119785;47956.28065579019;48043.519564804345;8179.182081791803;8307.044689860653;9246.537673116334;9404.952975235114;10779.78440431191;10961.342319460819;11038;11069.446527673585;11089.2237543372;11091.112710983101;11085.72285692856;11204.103671706243;11215.214934954556;11218;11228.831567526493;11034.337939723642;11072.667819965414;11104.888951110506;11133.888661113448;11135.554577816878;10825.458727063615;10911.399873006982;10938.507767150417;10939.617113401073;10944.343339399582;43751;44081;44218.115637687064;44354.33468497975;44357.33463998043;3779.263043706478;3869.342211823997;3890.435886796427;4405.8237670493145;4485.798139083749;32935.50596741051;33407.665923340945;33444.83277583621;33553.83223083893;33723.325533489195;3285.950710739341;3342.5320455136302;3404.8127352995575;3426.777259478126;3535.328287625343;294102;294526;295572;295693;295779;60730.39269607336;61205.69397152986;74327;76385.61807190928;76586.6170669143;72003;72024.63987680028;72067.63966180135;72081;72137.27862721306;19108.426747197605;19210.711839322423;19355.80644193568;19395.903020484806;19406.514837129183;14864.777028344584;14994.550163495112;15009.774853377208;15046.548603541693;15049.473268435771;23029;23244.3026709199;23248.302550923498;23264.720440375604;23270.418239544146;8645.178708022728;8912.688055918012;9014.18872301496;9038.276937844958;9063.682771103111;1048.6539441984128;1101.2676570080966;1114.2367478277406;1155.62633753049;1198.5086114692933;30874.536881946788;31860.840695796374;31929.68070319267;35051.82474087664;36027.63972360295;1132.105636547135;1161.5353858456685;1161.7444162284285;1181.004103065816;1235.4625737804083;13676.794848077088;15881.444149454714;16133.838661613234;16427.178641067898;16859.15704214809;341282;343923;345985;347681;348334;59995.70002150049;60755;60777.69611152005;60786.696066519384;60886.391136088074;1420.739403069957;1427.6145440731152;1530.0972426268333;1556.6497538053848;1580.6127498762876;31794.523082153322;32251.193720157185;32442.188945276557;34097.48853767146;36030.279394412486;55761.721191393786;56298.43701563096;56434;56447.71776141093;56499;344323;348126;348231;348384;349524;47815.76092119517;48009.5199048005;49122.263166051824;49139.508604914925;49472.50527494823;50244;50803.23795143002;51048.23427648514;51060.744696276284;51270.230946535085;42867;43915.34126988034;44007.559924400346;44745.32882006707;45043.099138017715;46495.767521162175;46501;46760.298595521774;46770;48468;41226.536781660856;41520.169596607295;41687.791561042;42005.57994420139;42133.57866421297;36246.27507449889;36388;36578.26843463063;37303.067423314635;37545.62454375421;27691.584626231026;28241.15276541707;28420.715792841806;28455.43089138247;28464.288392789516 18:10:54 INFO - 2016-01-05 18:10:54,914 DEBUG : BROWSER_OUTPUT: |2;jquery.html;390.19814281651213;394.78286942181813;395.29242655646374;398.59940759536664;402.25984189092065;13385.109591992641;18196.545086372847;19369.1283892225;19584.216631334748;20243.089060992254;19452.12465439055;19517.707234391495;19518.31685890994;19550.31573894914;19552.41342759716;5820.417958204188;5944.702764861761;5947.732352044169;5953.077273022688;5957.642541447505;5238.13570760824;5365.892682146355;5893.204417403638;5917.674527900964;5935.169076329317;3938.1336106056765;3996.9400458993136;5298.682079075248;5303.893922121555;5305.1776974569;5284.973575132119;5370.91943620846;5433.402325744165;5433.918491222635;5444.564434845222;4694.896699275668;4695.483496815347;4708.7174769513595;4818.253170758503;4831.444383895861;15201.847981520265;15318.617034574114;15536.06783592988;15547.378104875792;15549.922250388789;2264.9660255096187;2297.7472478027403;2353.576356255873;2354.9646755298513;2393.73668896423;11162.218644694874;11193.776124477465;11218.831717524243;11244.831327530093;11517;1851.3242666426793;1891.1489829576608;1901.1634880652468;1906.5328994396323;1946.6885298352195;67370;70173.2982670177;72441;72467.91298130533;72801.27198727998;7831.725889593837;7890.408219383455;7895.289423951872;7957.840843183104;8007.239312265326;9620.71137865865;9622.759431014141;9627.90372096284;9634.662786802428;9644;6237.656928868865;6243.344448832807;6409.230892292955;6600.174978127734;6601;6664.733410663616;6668.199816022108;6688.498362622823;6734.831629209211;6738.764143254963;3713.9071523212137;3763.849446022183;3769.1896242308035;3786.1291902862495;3794.924101517954;1294.6698591859015;1299.27240745183;1303.8565757766637;1304.302198323892;1356.4913157565913;503.62983207342575;509.88017815813254;525.1440152551316;525.3590619444271;549.4560385218684;11973.820392694117;12715.427805748579;13037.217766934014;13089.541866034833;13217.14088584239;547.1054825360565;548.1613131908186;549.5054450994137;559.1975515135712;562.4909456941474;5566.721663916788;6059.939400606026;7356.668949897267;7379.852402952019;7557.130929943017;612232;683523;686618;688681;694482;79431;81616.18383816205;81773;83217.58391208005;84370;587.6855882103055;591.8757061017235;592.3661682000219;593.2169536212159;595.1251660059705;12375.381230938237;12500.562480313145;12552.87447125517;12586.496540138658;12612.180208286432;39408.6059139405;39807.60192398155;40014;40304;40909.1818163641;53216;54506.454935450136;54633.45366546284;54633.72683136559;54731.72634136804;7628.122765881994;7640.923590764021;7726.381889448943;7803.804904877424;7811.648475818488;13724.313784310545;13767.105138165987;13933.23367214813;13934.790978135134;14024.929875350557;11806.527738890349;11850.940745296219;11965.162438629213;12215.816762748389;12382.318972456602;11778.528858845893;11881.5841445549;12035.338056406981;12171.45228464739;12204.511819527119;12187.085968552217;12200.816987745013;12356.876431235572;12441;12544.435500402322;6736.93263067363;7728.381729461742;7738.806529836799;7743.845123097394;7780.533168009937;11582.07343412508;11849.703757406134;11966.521339146684;12140.696482587653;12204.023678105908 18:10:54 INFO - 2016-01-05 18:10:54,918 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1553.1690545558129;1778.422012845825;1798.820117988201;1855.7680289963762;1880.3982725527828;8410.747677569667;8613.44012639178;8615.39692221544;8617.784555386103;8634.050254472018;7260.310270524292;7442.292982166699;7975.76072717818;8148.755537333875;8509.489430634167;2407.1695265133544;2409.1929203716777;2434.6834911461483;2436.5492383908945;2444.559979203743;2169.034779523113;2458.864762438065;2465.6917885264343;2491.862947537885;2500.949981000379;2143.07847625521;2371.92884213473;2412.8914198861007;2415.6497307890345;2415.7825795678386;2375.2636682628354;2410.0239403041796;2412.5054363855365;2414.1429792423614;2416.734159242482;2391.6053851114543;2413.9517209655883;2472.320111969205;2493.5012997400413;2494;8497.405181637288;8563.529005904673;8567.31461483086;8572.228499435081;8573.399862009663;5183.3002544656365;5259.526642602184;5266.867623460936;5266.8683282917855;5270.367555893279;3295.5221492883397;3301.273719781644;3331.700146986783;3389.1018879996823;3419.384510788057;1081.7836432713489;1102.448775612194;1115.2806439846254;1120.5517792882831;1128.463979609687;11914.523419063227;11924.940375298069;11961.581344652983;11978.58074967372;11985.520579176822;4730.929036064462;4747.667663263607;4812.639052071108;4813.085513752376;4850.6362022847725;10042.598296068221;10086.193104551618;10091.041351071637;10101.444420556792;10111.646092386878;5262.394824595221;5263.499967503081;5265.341832270966;5265.447128051576;5273.3935597406025;4795.064962332312;4812.639052071108;4815.277708343777;4817.301491283744;4819.903601928012;4076;4117.485314335708;4117.711760176759;4155.148194620096;4162.375643653416;1237.8638349781518;1262.8042653388738;1291.122037014839;1310.698539335939;1313.1136482874213;1077.7413420778958;1148.322489731046;1152.8962393384468;1367.4530187924909;1443.4370595467776;7925.405594580429;7985.600719963979;7993.080795708568;8063.153368896299;8131;1218.8842060004463;1219.5975328141692;1219.7499512600073;1233.2908577567755;1241.8016613967566;6221.96889015552;6304.464120549713;6322.209723784527;6342.714577844008;6383.840403989844;10208.948955255177;10445.059944605022;10456.424896630606;10456.843147352796;10475.580976760844;10095.899041009643;10341.534630941625;10361.326513776581;10368.222383321281;10376.273660843668;7933.801654958672;7968.282854543119;7975.202479751978;7977.082635496876;7989.320907722792;8051.355891528547;8090.555019473985;8147.185281471805;8178.591070446335;8213.178682131738;9126.45241285525;9233.307501937246;9260.39807412516;9267.351285410226;9267.67563135287;9350;9459;9487;9564;9580.76048098803;7682.769516914502;7918.722844700408;7991.400644951768;7994.640241189277;8000.799980000546;7991.560464174526;8037.156098609562;8058.153893841297;8059.194080591894;8059.758207253792;6386.584871983307;7256.7460138896995;7354.6690398931105;7410.851782964418;7682.347000504796;7502.512336698097;7515.661795219119;7600.581967991813;7609.581473018816;7626.466147369631;7727.995360603086;7758.612069396395;7758.9612051939375;7771.145174030739;7773.144954055162;6876.518643694893;7103.183133939663;7150.928490715234;7191.568505889663;7192.568445893263;7310.488265821342;7342.229065948212;7349.70601175947;7351.301626345488;7351.77944661661 18:10:54 INFO - 2016-01-05 18:10:54,918 DEBUG : BROWSER_OUTPUT: |4;prototype.html;370.0895796341001;371.48363774353646;374.72644969172484;377.8696349759332;378.34923930838954;5847.473727364536;5921.763129474826;5926.644401335918;5963.135345374918;5976.163337132794;5793.942060579393;5840.06558950568;5874.706264686759;5891.823245302637;5896.941030589693;4665.276882083265;4667.276572131325;4668.463126740435;4675.696079754822;4677.766111694419;4531.77341132942;4540.250858608342;4602.769861506928;4666.743329116897;4677.321788340706;4583.770811459431;4610.815567377318;4614.700044497097;4634.791434385461;4635.791389387468;4582.33556134362;4591.517890621503;4592.380028696117;4595.931061034087;4608.677392582521;4069.674426045909;4069.8779036628653;4074.9185016299803;4080.4287399764057;4097.651699605537;5539.473749993744;5645.802396916089;5733.8566535836935;5783.681897495636;5787.913181302284;5469.206964990094;5470;5475.479829416239;5475.72621368934;5478.424765399615;6047.78832740852;6353.8411539711415;6359.268684101295;6361.141245931786;6365.395287447685;4469.10617876426;4501.099780044005;4553.180427523044;4558.61251793598;4562.1560011398;6442.291347951722;6460.709268082947;6461.12774775404;6469.288378278386;6475.643839588821;4665.4168228971375;4669.906601868011;4670.346151538787;4670.95329046712;4671.976640116845;4534.410526631516;4602.608778253819;4616.676832621752;4733.644976626767;4744.525547445228;5270.1040823060175;5274.92087618686;5276.15581506957;5276.472352764769;5279.498447647467;6283.088952102011;6283.874322513524;6287.779927702509;6288.465480434123;6292.244930608356;1667.3497336038954;1678.639092595098;1685.1405783050732;1689.4171510828744;1689.9831001690072;1138.1122724275078;1140.2075557487633;1140.441183819939;1155.664857191405;1172.5075468303332;6182.938170618237;6183.567150299526;6188.1027251048345;6192.59748116371;6194.473469755031;6589.604623722496;6590.242122155918;6591.802245932629;6596.670166491656;6596.96701516499;6057.061155520902;6174.845628859224;6177.81466556004;6179.721912513947;6187.597806142587;3608.0438683748825;3640.6723394894057;3643.417053271464;3643.6720695137565;3646.033801042726;6739.393454589111;6745.392914637608;6749.493787965922;6751.156105486814;6752.561083529555;6627.37039981201;6720.092787473726;6723;6724.361185687352;6724.562903411263;6699.263081061079;6699.564528305645;6713.26154123046;6719.42884854783;6746.561473504206;6289.96855015722;6368.267649220214;6459.128017717736;6515.869682606416;6536.7712130075215;6449.677516124081;6457.935420645921;6462.3537646235;6465.191851018622;6473.449756770538;6666.733330666719;6676.732930682719;6677.532572719863;6678.165229346332;6701.899471507833;4507.75207363585;4571.794269257958;4580.358749774968;4629.213033784332;4642.767861606973;6428.4214420702365;6441.742330306735;6640.9335906642245;6649.900251496329;6667.599944003376;6252.3122456530655;6254.530910181846;6448.967755161194;6452.257990330984;6472.546921715435;6193.597416167935;6223.222097237845;6312.589681670676;6481.902771458337;6482.805515834532;6338.429541341301;6347.650879201504;6358.618482891041;6376.744930202925;6380.202474690664;6437.678116094083;6474.125992990886;6498.480121590356;6501.967490162708;6508.316626754312;6228.159198508325;6312.116303717391;6348.873022539431;6353.078803573363;6359.523035772429 18:10:54 INFO - 2016-01-05 18:10:54,922 DEBUG : BROWSER_OUTPUT: |5;yui.html;829.9502029878208;871.8910136232971;878.8813510176125;972.7130496503531;975.3903810118676;14700.70598588029;15342.769858452131;15350.46273380432;15368.923155384235;15371.692566148684;14199.929000354983;14216.715665686681;14304.856951430482;14356.210408427532;14378.49675261366;764.1365257259289;766.7814672818248;933.701215611003;951.1059603972278;951.6764300137952;908.0737647599477;915.3546749541557;916.326500022483;916.6974898283551;917.0554329041065;971.2958105373618;974.3131092579714;980.9607615695363;981.3228872078279;998.5955687946393;810.6797814863125;812.0661239574478;966.8211380894487;970.1365784451843;997.5560875410446;706.6184260499324;713.165596252386;713.5825542057902;720.9243029481934;733.3986131372277;14834.703305933821;14856.405743770236;14881.10713357202;14927.701445971019;14965.92517037408;13961.232132232723;14112.92943535286;14115.153090814481;14193.07744996572;14218.928905355408;11017.724556886062;11189.608363707313;11303.208775385727;11327.32036077838;11421.714457138554;3888.591697871739;3941.4679018332445;3950.7036972227197;3960.801959902022;4012.7592344459426;50560.49439505632;53953;54000;54674;54786.17820732732;11204.215704900578;11230.213885028134;11278.436078196057;11302.265352752045;11521.423928803526;20805.687914681293;20843.270485533238;20848;20887.79112208889;20891.164353425997;8196;8336.124706905808;8358.791030224293;8422.452540584842;8442.704505342284;4359.12817436514;4425.756583387944;4449.1101779644205;4488.955110448854;4509.075639493896;3310.966890331114;3329.900102996914;3340.1816554944194;3349.129226401153;3358.227607650254;497.5024975024975;515.5488947171225;518.3106468397023;520.2534363188836;521.3977855576815;554.287740253772;572.2189211725964;582.5659883386904;604.1119554255238;610.1640752169557;8981.640734370683;9018.188363047357;9087.909120908706;9318.813623727623;9495.620175193053;617.1514168018974;633.7084940927225;638.4541217259176;642.1395330257477;657.9572327798678;4130.339145736668;4135.276326642826;4149.958500415018;4159.085001299709;4225.915481690349;69621.30378696148;70295.64852175809;70618.29381706117;71114.2888571118;71750;60541.69729151326;61331.6933415339;61956;61992;62283.37716622867;54561;54747.45252547503;54806;55372.16941745877;55558.444415556136;8339.874901876356;8418.452800567944;8697.434666746642;8721.258693011165;8797.164269394398;39681;40053.599464006154;40352.39471407873;41115;41789;50604;51463;51562.74218629033;52126.21810672919;52165;8016.559089250147;8143.144969778088;8220.876686849582;8244.587770611566;8316.334693224648;11404.315741055563;11461.598844040418;11693.123015774021;11837.348945807718;11920.940395297968;10407.219458540489;10444.895551044696;10491.370517768959;10543;10789.460526973462;9536.904630953879;9669.758256043655;9836.3606365586;9940;9951.701448956543;10606.416647084176;10678.252522323673;10681.412522311348;10845.186611004365;11086.944565277123;10395;10541.367517948947;10542.209334299805;10756.784864302514;10843.132549396187;10015.599376024877;10359.326643768432;10377.169826413718;10377.481125943521;10410.219233557666 18:10:54 INFO - 2016-01-05 18:10:54,922 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:10:54 INFO - 2016-01-05 18:10:54,922 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:10:54 INFO - 2016-01-05 18:10:54,922 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,726 18:10:54 INFO - 2016-01-05 18:10:54,922 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:10:54 INFO - 2016-01-05 18:10:54,923 DEBUG : BROWSER_OUTPUT: __startTimestamp1452046254906__endTimestamp 18:10:54 INFO - 2016-01-05 18:10:54,923 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,923 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:10:54 INFO - 2016-01-05 18:10:54,924 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:10:54 INFO - 2016-01-05 18:10:54,924 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,924 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5676.72 Median:5897.35 stddev:644.36 (10.9%) stddev-sans-first:619.14 18:10:54 INFO - 2016-01-05 18:10:54,924 DEBUG : BROWSER_OUTPUT: Values: 3315.6 3371.4 3383.4 3383.8 3409.9 5626.8 5633.8 5696.3 5701.3 5712.9 5728.1 5735.3 5753.5 5760.5 5760.5 5040.8 5187.5 5221.8 5292.8 5297.1 5224.1 5252.1 5261.9 5278.7 5279.6 5102.5 5145.0 5171.8 5209.0 5210.3 5224.8 5229.0 5252.7 5261.4 5263.6 5194.4 5216.1 5219.4 5278.2 5292.3 5315.2 5338.5 5421.7 5433.2 5457.9 6115.9 6130.4 6149.3 6164.1 6169.6 5999.3 6175.3 6203.6 6207.5 6215.9 5632.9 5726.1 5730.6 5731.7 5751.1 6233.2 6261.6 6284.6 6301.0 6304.6 5671.0 5758.7 6088.0 6093.5 6096.3 6050.5 6127.7 6132.3 6138.5 6141.6 6125.0 6125.6 6128.8 6131.1 6134.3 5998.2 6121.7 6121.7 6136.1 6137.5 4571.0 4634.5 4637.2 4650.7 4678.1 4130.9 4133.1 4140.0 4150.5 4160.7 5586.4 5688.8 5826.8 5831.7 5833.6 6040.4 6081.6 6100.5 6102.8 6113.1 5756.1 5833.9 5844.0 5850.4 5858.3 5193.0 5237.5 5267.3 5284.0 5287.8 6313.7 6333.5 6338.5 6338.5 6338.6 6169.5 6183.8 6205.1 6213.2 6214.9 6156.4 6191.0 6292.5 6301.9 6333.8 6060.0 6061.5 6061.8 6083.1 6092.9 6149.5 6149.8 6152.2 6153.2 6178.5 5783.1 5900.6 5948.2 6062.8 6076.8 5970.0 6003.4 6006.0 6020.9 6052.1 5601.7 5893.4 5894.3 5899.8 5900.6 5891.5 5897.5 6013.0 6032.8 6043.5 5897.4 5938.1 5940.3 5941.2 5985.5 18:10:54 INFO - 2016-01-05 18:10:54,925 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,925 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53526.16 Median:348954.00 stddev:86145.63 (24.7%) stddev-sans-first:86295.78 18:10:54 INFO - 2016-01-05 18:10:54,925 DEBUG : BROWSER_OUTPUT: Values: 640.5 641.0 645.7 649.0 657.2 42420.8 42953.6 42968.4 42980.0 42994.4 47326.5 47861.8 47915.8 47956.3 48043.5 8179.2 8307.0 9246.5 9405.0 10779.8 10961.3 11038.0 11069.4 11089.2 11091.1 11085.7 11204.1 11215.2 11218.0 11228.8 11034.3 11072.7 11104.9 11133.9 11135.6 10825.5 10911.4 10938.5 10939.6 10944.3 43751.0 44081.0 44218.1 44354.3 44357.3 3779.3 3869.3 3890.4 4405.8 4485.8 32935.5 33407.7 33444.8 33553.8 33723.3 3286.0 3342.5 3404.8 3426.8 3535.3 294102.0 294526.0 295572.0 295693.0 295779.0 60730.4 61205.7 74327.0 76385.6 76586.6 72003.0 72024.6 72067.6 72081.0 72137.3 19108.4 19210.7 19355.8 19395.9 19406.5 14864.8 14994.6 15009.8 15046.5 15049.5 23029.0 23244.3 23248.3 23264.7 23270.4 8645.2 8912.7 9014.2 9038.3 9063.7 1048.7 1101.3 1114.2 1155.6 1198.5 30874.5 31860.8 31929.7 35051.8 36027.6 1132.1 1161.5 1161.7 1181.0 1235.5 13676.8 15881.4 16133.8 16427.2 16859.2 341282.0 343923.0 345985.0 347681.0 348334.0 59995.7 60755.0 60777.7 60786.7 60886.4 1420.7 1427.6 1530.1 1556.6 1580.6 31794.5 32251.2 32442.2 34097.5 36030.3 55761.7 56298.4 56434.0 56447.7 56499.0 344323.0 348126.0 348231.0 348384.0 349524.0 47815.8 48009.5 49122.3 49139.5 49472.5 50244.0 50803.2 51048.2 51060.7 51270.2 42867.0 43915.3 44007.6 44745.3 45043.1 46495.8 46501.0 46760.3 46770.0 48468.0 41226.5 41520.2 41687.8 42005.6 42133.6 36246.3 36388.0 36578.3 37303.1 37545.6 27691.6 28241.2 28420.7 28455.4 28464.3 18:10:54 INFO - 2016-01-05 18:10:54,925 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,926 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32311.19 Median:4824.85 stddev:110298.61 (2286.1%) stddev-sans-first:110581.98 18:10:54 INFO - 2016-01-05 18:10:54,926 DEBUG : BROWSER_OUTPUT: Values: 390.2 394.8 395.3 398.6 402.3 13385.1 18196.5 19369.1 19584.2 20243.1 19452.1 19517.7 19518.3 19550.3 19552.4 5820.4 5944.7 5947.7 5953.1 5957.6 5238.1 5365.9 5893.2 5917.7 5935.2 3938.1 3996.9 5298.7 5303.9 5305.2 5285.0 5370.9 5433.4 5433.9 5444.6 4694.9 4695.5 4708.7 4818.3 4831.4 15201.8 15318.6 15536.1 15547.4 15549.9 2265.0 2297.7 2353.6 2355.0 2393.7 11162.2 11193.8 11218.8 11244.8 11517.0 1851.3 1891.1 1901.2 1906.5 1946.7 67370.0 70173.3 72441.0 72467.9 72801.3 7831.7 7890.4 7895.3 7957.8 8007.2 9620.7 9622.8 9627.9 9634.7 9644.0 6237.7 6243.3 6409.2 6600.2 6601.0 6664.7 6668.2 6688.5 6734.8 6738.8 3713.9 3763.8 3769.2 3786.1 3794.9 1294.7 1299.3 1303.9 1304.3 1356.5 503.6 509.9 525.1 525.4 549.5 11973.8 12715.4 13037.2 13089.5 13217.1 547.1 548.2 549.5 559.2 562.5 5566.7 6059.9 7356.7 7379.9 7557.1 612232.0 683523.0 686618.0 688681.0 694482.0 79431.0 81616.2 81773.0 83217.6 84370.0 587.7 591.9 592.4 593.2 595.1 12375.4 12500.6 12552.9 12586.5 12612.2 39408.6 39807.6 40014.0 40304.0 40909.2 53216.0 54506.5 54633.5 54633.7 54731.7 7628.1 7640.9 7726.4 7803.8 7811.6 13724.3 13767.1 13933.2 13934.8 14024.9 11806.5 11850.9 11965.2 12215.8 12382.3 11778.5 11881.6 12035.3 12171.5 12204.5 12187.1 12200.8 12356.9 12441.0 12544.4 6736.9 7728.4 7738.8 7743.8 7780.5 11582.1 11849.7 11966.5 12140.7 12204.0 18:10:54 INFO - 2016-01-05 18:10:54,926 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,926 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6003.97 Median:5221.41 stddev:3121.88 (59.8%) stddev-sans-first:3112.72 18:10:54 INFO - 2016-01-05 18:10:54,927 DEBUG : BROWSER_OUTPUT: Values: 1553.2 1778.4 1798.8 1855.8 1880.4 8410.7 8613.4 8615.4 8617.8 8634.1 7260.3 7442.3 7975.8 8148.8 8509.5 2407.2 2409.2 2434.7 2436.5 2444.6 2169.0 2458.9 2465.7 2491.9 2500.9 2143.1 2371.9 2412.9 2415.6 2415.8 2375.3 2410.0 2412.5 2414.1 2416.7 2391.6 2414.0 2472.3 2493.5 2494.0 8497.4 8563.5 8567.3 8572.2 8573.4 5183.3 5259.5 5266.9 5266.9 5270.4 3295.5 3301.3 3331.7 3389.1 3419.4 1081.8 1102.4 1115.3 1120.6 1128.5 11914.5 11924.9 11961.6 11978.6 11985.5 4730.9 4747.7 4812.6 4813.1 4850.6 10042.6 10086.2 10091.0 10101.4 10111.6 5262.4 5263.5 5265.3 5265.4 5273.4 4795.1 4812.6 4815.3 4817.3 4819.9 4076.0 4117.5 4117.7 4155.1 4162.4 1237.9 1262.8 1291.1 1310.7 1313.1 1077.7 1148.3 1152.9 1367.5 1443.4 7925.4 7985.6 7993.1 8063.2 8131.0 1218.9 1219.6 1219.7 1233.3 1241.8 6222.0 6304.5 6322.2 6342.7 6383.8 10208.9 10445.1 10456.4 10456.8 10475.6 10095.9 10341.5 10361.3 10368.2 10376.3 7933.8 7968.3 7975.2 7977.1 7989.3 8051.4 8090.6 8147.2 8178.6 8213.2 9126.5 9233.3 9260.4 9267.4 9267.7 9350.0 9459.0 9487.0 9564.0 9580.8 7682.8 7918.7 7991.4 7994.6 8000.8 7991.6 8037.2 8058.2 8059.2 8059.8 6386.6 7256.7 7354.7 7410.9 7682.3 7502.5 7515.7 7600.6 7609.6 7626.5 7728.0 7758.6 7759.0 7771.1 7773.1 6876.5 7103.2 7150.9 7191.6 7192.6 7310.5 7342.2 7349.7 7351.3 7351.8 18:10:54 INFO - 2016-01-05 18:10:54,927 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,927 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5340.07 Median:6052.42 stddev:1568.51 (25.9%) stddev-sans-first:1527.91 18:10:54 INFO - 2016-01-05 18:10:54,929 DEBUG : BROWSER_OUTPUT: Values: 370.1 371.5 374.7 377.9 378.3 5847.5 5921.8 5926.6 5963.1 5976.2 5793.9 5840.1 5874.7 5891.8 5896.9 4665.3 4667.3 4668.5 4675.7 4677.8 4531.8 4540.3 4602.8 4666.7 4677.3 4583.8 4610.8 4614.7 4634.8 4635.8 4582.3 4591.5 4592.4 4595.9 4608.7 4069.7 4069.9 4074.9 4080.4 4097.7 5539.5 5645.8 5733.9 5783.7 5787.9 5469.2 5470.0 5475.5 5475.7 5478.4 6047.8 6353.8 6359.3 6361.1 6365.4 4469.1 4501.1 4553.2 4558.6 4562.2 6442.3 6460.7 6461.1 6469.3 6475.6 4665.4 4669.9 4670.3 4671.0 4672.0 4534.4 4602.6 4616.7 4733.6 4744.5 5270.1 5274.9 5276.2 5276.5 5279.5 6283.1 6283.9 6287.8 6288.5 6292.2 1667.3 1678.6 1685.1 1689.4 1690.0 1138.1 1140.2 1140.4 1155.7 1172.5 6182.9 6183.6 6188.1 6192.6 6194.5 6589.6 6590.2 6591.8 6596.7 6597.0 6057.1 6174.8 6177.8 6179.7 6187.6 3608.0 3640.7 3643.4 3643.7 3646.0 6739.4 6745.4 6749.5 6751.2 6752.6 6627.4 6720.1 6723.0 6724.4 6724.6 6699.3 6699.6 6713.3 6719.4 6746.6 6290.0 6368.3 6459.1 6515.9 6536.8 6449.7 6457.9 6462.4 6465.2 6473.4 6666.7 6676.7 6677.5 6678.2 6701.9 4507.8 4571.8 4580.4 4629.2 4642.8 6428.4 6441.7 6640.9 6649.9 6667.6 6252.3 6254.5 6449.0 6452.3 6472.5 6193.6 6223.2 6312.6 6481.9 6482.8 6338.4 6347.7 6358.6 6376.7 6380.2 6437.7 6474.1 6498.5 6502.0 6508.3 6228.2 6312.1 6348.9 6353.1 6359.5 18:10:54 INFO - 2016-01-05 18:10:54,929 DEBUG : BROWSER_OUTPUT: 18:10:54 INFO - 2016-01-05 18:10:54,930 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15443.70 Median:26040.53 stddev:19061.77 (73.2%) stddev-sans-first:19083.65 18:10:54 INFO - 2016-01-05 18:10:54,932 DEBUG : BROWSER_OUTPUT: Values: 830.0 871.9 878.9 972.7 975.4 14700.7 15342.8 15350.5 15368.9 15371.7 14199.9 14216.7 14304.9 14356.2 14378.5 764.1 766.8 933.7 951.1 951.7 908.1 915.4 916.3 916.7 917.1 971.3 974.3 981.0 981.3 998.6 810.7 812.1 966.8 970.1 997.6 706.6 713.2 713.6 720.9 733.4 14834.7 14856.4 14881.1 14927.7 14965.9 13961.2 14112.9 14115.2 14193.1 14218.9 11017.7 11189.6 11303.2 11327.3 11421.7 3888.6 3941.5 3950.7 3960.8 4012.8 50560.5 53953.0 54000.0 54674.0 54786.2 11204.2 11230.2 11278.4 11302.3 11521.4 20805.7 20843.3 20848.0 20887.8 20891.2 8196.0 8336.1 8358.8 8422.5 8442.7 4359.1 4425.8 4449.1 4489.0 4509.1 3311.0 3329.9 3340.2 3349.1 3358.2 497.5 515.5 518.3 520.3 521.4 554.3 572.2 582.6 604.1 610.2 8981.6 9018.2 9087.9 9318.8 9495.6 617.2 633.7 638.5 642.1 658.0 4130.3 4135.3 4150.0 4159.1 4225.9 69621.3 70295.6 70618.3 71114.3 71750.0 60541.7 61331.7 61956.0 61992.0 62283.4 54561.0 54747.5 54806.0 55372.2 55558.4 8339.9 8418.5 8697.4 8721.3 8797.2 39681.0 40053.6 40352.4 41115.0 41789.0 50604.0 51463.0 51562.7 52126.2 52165.0 8016.6 8143.1 8220.9 8244.6 8316.3 11404.3 11461.6 11693.1 11837.3 11920.9 10407.2 10444.9 10491.4 10543.0 10789.5 9536.9 9669.8 9836.4 9940.0 9951.7 10606.4 10678.3 10681.4 10845.2 11086.9 10395.0 10541.4 10542.2 10756.8 10843.1 10015.6 10359.3 10377.2 10377.5 10410.2 18:10:54 INFO - 2016-01-05 18:10:54,932 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:10:54 INFO - 2016-01-05 18:10:54,932 DEBUG : BROWSER_OUTPUT: 18:10:55 INFO - 2016-01-05 18:10:55,436 INFO : Browser exited with error code: 0 18:10:55 INFO - 2016-01-05 18:10:55,455 INFO : Completed test dromaeo_css (00:18:29) 18:10:55 INFO - 2016-01-05 18:10:55,455 INFO : Starting test kraken 18:10:55 INFO - 2016-01-05 18:10:55,455 DEBUG : operating with platform_type : linux_ 18:10:55 INFO - 2016-01-05 18:10:55,455 INFO : Initialising browser for kraken test... 18:10:55 INFO - 2016-01-05 18:10:55,461 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdOOZdX/profile http://localhost:58906/getInfo.html 18:10:59 INFO - 2016-01-05 18:10:59,034 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:10:59 INFO - 2016-01-05 18:10:59,034 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:10:59 INFO - 2016-01-05 18:10:59,035 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:10:59 INFO - 2016-01-05 18:10:59,035 DEBUG : BROWSER_OUTPUT: __metrics 18:10:59 INFO - 2016-01-05 18:10:59,137 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] 18:11:00 INFO - 2016-01-05 18:11:00,332 INFO : Browser initialized. 18:11:00 INFO - 2016-01-05 18:11:00,333 INFO : Running cycle 1/1 for kraken test... 18:11:00 INFO - 2016-01-05 18:11:00,333 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdOOZdX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:11:11 INFO - 2016-01-05 18:11:11,853 DEBUG : BROWSER_OUTPUT: RSS: Main: 129417216 18:11:11 INFO - 2016-01-05 18:11:11,853 DEBUG : BROWSER_OUTPUT: 18:11:14 INFO - 2016-01-05 18:11:14,308 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58906/tests/kraken/driver.html?testName=audio-beat-detection) 18:11:14 INFO - 2016-01-05 18:11:14,876 DEBUG : BROWSER_OUTPUT: RSS: Main: 164528128 18:11:14 INFO - 2016-01-05 18:11:14,876 DEBUG : BROWSER_OUTPUT: 18:11:17 INFO - 2016-01-05 18:11:17,459 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58906/tests/kraken/driver.html?testName=audio-dft) 18:11:18 INFO - 2016-01-05 18:11:18,033 DEBUG : BROWSER_OUTPUT: RSS: Main: 161693696 18:11:18 INFO - 2016-01-05 18:11:18,034 DEBUG : BROWSER_OUTPUT: 18:11:20 INFO - 2016-01-05 18:11:20,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58906/tests/kraken/driver.html?testName=audio-fft) 18:11:21 INFO - 2016-01-05 18:11:21,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 157474816 18:11:21 INFO - 2016-01-05 18:11:21,374 DEBUG : BROWSER_OUTPUT: 18:11:23 INFO - 2016-01-05 18:11:23,325 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58906/tests/kraken/driver.html?testName=audio-oscillator) 18:11:23 INFO - 2016-01-05 18:11:23,887 DEBUG : BROWSER_OUTPUT: RSS: Main: 156934144 18:11:23 INFO - 2016-01-05 18:11:23,888 DEBUG : BROWSER_OUTPUT: 18:11:26 INFO - 2016-01-05 18:11:26,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58906/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:11:26 INFO - 2016-01-05 18:11:26,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 156082176 18:11:26 INFO - 2016-01-05 18:11:26,591 DEBUG : BROWSER_OUTPUT: 18:11:29 INFO - 2016-01-05 18:11:29,450 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58906/tests/kraken/driver.html?testName=imaging-darkroom) 18:11:30 INFO - 2016-01-05 18:11:30,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 156868608 18:11:30 INFO - 2016-01-05 18:11:30,017 DEBUG : BROWSER_OUTPUT: 18:11:33 INFO - 2016-01-05 18:11:33,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58906/tests/kraken/driver.html?testName=imaging-desaturate) 18:11:33 INFO - 2016-01-05 18:11:33,892 DEBUG : BROWSER_OUTPUT: RSS: Main: 157564928 18:11:33 INFO - 2016-01-05 18:11:33,892 DEBUG : BROWSER_OUTPUT: 18:11:36 INFO - 2016-01-05 18:11:36,593 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58906/tests/kraken/driver.html?testName=json-parse-financial) 18:11:37 INFO - 2016-01-05 18:11:37,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 158158848 18:11:37 INFO - 2016-01-05 18:11:37,167 DEBUG : BROWSER_OUTPUT: 18:11:38 INFO - 2016-01-05 18:11:38,675 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58906/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:11:39 INFO - 2016-01-05 18:11:39,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 162275328 18:11:39 INFO - 2016-01-05 18:11:39,236 DEBUG : BROWSER_OUTPUT: 18:11:40 INFO - 2016-01-05 18:11:40,945 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:11:41 INFO - 2016-01-05 18:11:41,533 DEBUG : BROWSER_OUTPUT: RSS: Main: 161206272 18:11:41 INFO - 2016-01-05 18:11:41,533 DEBUG : BROWSER_OUTPUT: 18:11:43 INFO - 2016-01-05 18:11:43,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:11:44 INFO - 2016-01-05 18:11:44,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 166547456 18:11:44 INFO - 2016-01-05 18:11:44,095 DEBUG : BROWSER_OUTPUT: 18:11:46 INFO - 2016-01-05 18:11:46,449 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:11:47 INFO - 2016-01-05 18:11:47,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 170532864 18:11:47 INFO - 2016-01-05 18:11:47,017 DEBUG : BROWSER_OUTPUT: 18:11:49 INFO - 2016-01-05 18:11:49,424 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:11:49 INFO - 2016-01-05 18:11:49,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 169480192 18:11:49 INFO - 2016-01-05 18:11:49,989 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,582 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58906/tests/kraken/driver.html?testName=ai-astar) 18:11:51 INFO - 2016-01-05 18:11:51,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 227729408 18:11:51 INFO - 2016-01-05 18:11:51,833 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,833 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:11:51 INFO - 2016-01-05 18:11:51,833 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:11:51 INFO - 2016-01-05 18:11:51,833 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:11:51 INFO - 2016-01-05 18:11:51,833 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:11:51 INFO - 2016-01-05 18:11:51,833 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;112;103;109;112;109;100;110;99;110 18:11:51 INFO - 2016-01-05 18:11:51,834 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;122;147;162;131;143;149;122;178;123 18:11:51 INFO - 2016-01-05 18:11:51,834 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;160;160;159;160;160;163;160;161 18:11:51 INFO - 2016-01-05 18:11:51,834 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;70;146;71;71;71;71;155;71 18:11:51 INFO - 2016-01-05 18:11:51,834 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;88;89;132;89;90;89;137;88;90;140 18:11:51 INFO - 2016-01-05 18:11:51,834 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;110;110;110;110;110;109;109 18:11:51 INFO - 2016-01-05 18:11:51,834 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;147;145;145;146;146;145;146;145 18:11:51 INFO - 2016-01-05 18:11:51,834 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;95;96;95;95;96 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;55;54;54;55;55;55;65;53;55 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;62;62;62;63;63;62;66;62 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;77;79;77;78;77;87;78;80;79 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;123;123;113;109;116;114;111;121;109 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;135;136;134;127;134;135;135;134;135 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;60;63;64;60;60;61;60;61;61;61 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:11:51 INFO - 2016-01-05 18:11:51,835 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:11:51 INFO - 2016-01-05 18:11:51,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,861 18:11:51 INFO - 2016-01-05 18:11:51,836 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:11:51 INFO - 2016-01-05 18:11:51,836 DEBUG : BROWSER_OUTPUT: __startTimestamp1452046311831__endTimestamp 18:11:51 INFO - 2016-01-05 18:11:51,836 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,837 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:11:51 INFO - 2016-01-05 18:11:51,837 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:11:51 INFO - 2016-01-05 18:11:51,837 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,837 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:110.00 stddev:4.81 (4.4%) stddev-sans-first:5.06 18:11:51 INFO - 2016-01-05 18:11:51,837 DEBUG : BROWSER_OUTPUT: Values: 109.0 112.0 103.0 109.0 112.0 109.0 100.0 110.0 99.0 110.0 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.30 Median:146.50 stddev:18.46 (12.6%) stddev-sans-first:19.53 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: Values: 146.0 122.0 147.0 162.0 131.0 143.0 149.0 122.0 178.0 123.0 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.14 (0.7%) stddev-sans-first:1.12 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 160.0 160.0 159.0 160.0 160.0 163.0 160.0 161.0 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.70 Median:71.00 stddev:33.69 (47.5%) stddev-sans-first:35.26 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 70.0 146.0 71.0 71.0 71.0 71.0 155.0 71.0 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,838 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.20 Median:89.00 stddev:22.95 (25.8%) stddev-sans-first:23.68 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: Values: 88.0 89.0 132.0 89.0 90.0 89.0 137.0 88.0 90.0 140.0 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 109.0 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 147.0 145.0 145.0 146.0 146.0 145.0 146.0 145.0 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:11:51 INFO - 2016-01-05 18:11:51,839 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:3.47 (6.3%) stddev-sans-first:3.57 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: Values: 53.0 55.0 54.0 54.0 55.0 55.0 55.0 65.0 53.0 55.0 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 62.0 62.0 62.0 63.0 63.0 62.0 66.0 62.0 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:78.50 stddev:2.98 (3.8%) stddev-sans-first:3.14 18:11:51 INFO - 2016-01-05 18:11:51,840 DEBUG : BROWSER_OUTPUT: Values: 78.0 77.0 79.0 77.0 78.0 77.0 87.0 78.0 80.0 79.0 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.60 Median:116.50 stddev:5.36 (4.6%) stddev-sans-first:5.66 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: Values: 117.0 123.0 123.0 113.0 109.0 116.0 114.0 111.0 121.0 109.0 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.00 Median:135.00 stddev:2.54 (1.9%) stddev-sans-first:2.67 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: Values: 135.0 135.0 136.0 134.0 127.0 134.0 135.0 135.0 134.0 135.0 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.37 (2.2%) stddev-sans-first:1.39 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: Values: 60.0 63.0 64.0 60.0 60.0 61.0 60.0 61.0 61.0 61.0 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:11:51 INFO - 2016-01-05 18:11:51,841 DEBUG : BROWSER_OUTPUT: 18:11:52 INFO - 2016-01-05 18:11:52,500 INFO : Browser exited with error code: 0 18:11:52 INFO - 2016-01-05 18:11:52,556 INFO : Completed test kraken (00:00:57) 18:11:52 INFO - 2016-01-05 18:11:52,556 INFO : Starting test v8_7 18:11:52 INFO - 2016-01-05 18:11:52,556 DEBUG : operating with platform_type : linux_ 18:11:52 INFO - 2016-01-05 18:11:52,556 INFO : Initialising browser for v8_7 test... 18:11:52 INFO - 2016-01-05 18:11:52,563 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpImWT77/profile http://localhost:58906/getInfo.html 18:11:56 INFO - 2016-01-05 18:11:56,210 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:11:56 INFO - 2016-01-05 18:11:56,210 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:11:56 INFO - 2016-01-05 18:11:56,210 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:11:56 INFO - 2016-01-05 18:11:56,210 DEBUG : BROWSER_OUTPUT: __metrics 18:11:56 INFO - 2016-01-05 18:11:56,387 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] 18:11:57 INFO - 2016-01-05 18:11:57,520 INFO : Browser initialized. 18:11:57 INFO - 2016-01-05 18:11:57,521 INFO : Running cycle 1/1 for v8_7 test... 18:11:57 INFO - 2016-01-05 18:11:57,521 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpImWT77/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:12:09 INFO - 2016-01-05 18:12:09,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 128458752 18:12:09 INFO - 2016-01-05 18:12:09,155 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,496 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58906/tests/v8_7/run.html (next: http://localhost:58906/tests/v8_7/run.html) 18:12:30 INFO - 2016-01-05 18:12:30,747 DEBUG : BROWSER_OUTPUT: RSS: Main: 419827712 18:12:30 INFO - 2016-01-05 18:12:30,747 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,748 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:12:30 INFO - 2016-01-05 18:12:30,748 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:12:30 INFO - 2016-01-05 18:12:30,748 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:12:30 INFO - 2016-01-05 18:12:30,748 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:12:30 INFO - 2016-01-05 18:12:30,748 DEBUG : BROWSER_OUTPUT: |0;Richards;163.90755613833798 18:12:30 INFO - 2016-01-05 18:12:30,748 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.7000828500414 18:12:30 INFO - 2016-01-05 18:12:30,748 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.9552071668533 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5010 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: |4;RayTrace;940.7337723424271 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: |5;Earley;550.6607929515418 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: |6;Boyer;10350.515463917525 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: |7;RegExp;26000 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: |8;Splay;532.197977647685 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:12:30 INFO - 2016-01-05 18:12:30,749 DEBUG : BROWSER_OUTPUT: __startTimestamp1452046350747__endTimestamp 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.91 Median:163.91 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: Values: 163.9 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.70 Median:165.70 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: Values: 165.7 18:12:30 INFO - 2016-01-05 18:12:30,750 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,751 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.96 Median:279.96 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,751 DEBUG : BROWSER_OUTPUT: Values: 280.0 18:12:30 INFO - 2016-01-05 18:12:30,751 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,751 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5010.00 Median:5010.00 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,751 DEBUG : BROWSER_OUTPUT: Values: 5010.0 18:12:30 INFO - 2016-01-05 18:12:30,751 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,751 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:940.73 Median:940.73 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,752 DEBUG : BROWSER_OUTPUT: Values: 940.7 18:12:30 INFO - 2016-01-05 18:12:30,752 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,752 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:550.66 Median:550.66 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: Values: 550.7 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10350.52 Median:10350.52 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: Values: 10350.5 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26000.00 Median:26000.00 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: Values: 26000.0 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:532.20 Median:532.20 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: Values: 532.2 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: Values: 4817.3 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:12:30 INFO - 2016-01-05 18:12:30,753 DEBUG : BROWSER_OUTPUT: 18:12:31 INFO - 2016-01-05 18:12:31,400 INFO : Browser exited with error code: 0 18:12:31 INFO - 2016-01-05 18:12:31,415 INFO : Completed test v8_7 (00:00:38) 18:12:31 INFO - 2016-01-05 18:12:31,813 INFO : Completed test suite (00:20:05) 18:12:31 INFO - 2016-01-05 18:12:31,813 DEBUG : Working with test: dromaeo_css 18:12:31 INFO - 2016-01-05 18:12:31,813 DEBUG : Generating results file: dromaeo_css 18:12:31 INFO - 2016-01-05 18:12:31,815 DEBUG : Working with test: kraken 18:12:31 INFO - 2016-01-05 18:12:31,815 DEBUG : Generating results file: kraken 18:12:31 INFO - 2016-01-05 18:12:31,815 INFO : javascript benchmark 18:12:31 INFO - 2016-01-05 18:12:31,816 DEBUG : Working with test: v8_7 18:12:31 INFO - 2016-01-05 18:12:31,816 DEBUG : Generating results file: v8_7 18:12:31 INFO - 2016-01-05 18:12:31,816 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:12:31 INFO - 2016-01-05 18:12:31,870 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 18:12:31 INFO - 2016-01-05 18:12:31,870 DEBUG : process_Request line: dromaeo_css 7316.49 graph.html#tests=[[72,53,35]] 18:12:31 INFO - 2016-01-05 18:12:31,870 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:12:31 INFO - 2016-01-05 18:12:31,905 DEBUG : process_Request line: kraken 1458.10 graph.html#tests=[[232,53,35]] 18:12:31 INFO - 2016-01-05 18:12:31,905 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:12:31 INFO - 2016-01-05 18:12:31,934 DEBUG : process_Request line: v8_7 23229.24 graph.html#tests=[[230,53,35]] 18:12:31 INFO - 2016-01-05 18:12:31,935 INFO : javascript benchmark 18:12:31 INFO - 2016-01-05 18:12:31,939 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3315.5855518060243, 3371.426857434236, 3383.374075795978, 3383.8308084595765, 3409.897703068907, 5626.803061892834, 5633.8309850704445, 5696.287964004499, 5701.287339082614, 5712.857178570538, 5728.083506638938, 5735.340435849878, 5753.510951569122, 5760.45275698809, 5760.510356619692, 5040.84877453677, 5187.507186817255, 5221.791128354861, 5292.814751483699, 5297.073012222845, 5224.059669259532, 5252.054630166568, 5261.894762104775, 5278.656887302313, 5279.5512381447625, 5102.489751024905, 5145.0, 5171.7672704728375, 5209.0, 5210.322658054448, 5224.817131400383, 5229.0, 5252.658577192489, 5261.4475480074425, 5263.605229607795, 5194.37667479901, 5216.061109000379, 5219.399769026572, 5278.2082687596785, 5292.259083728281, 5315.229291752674, 5338.546223571001, 5421.728913554307, 5433.212184233264, 5457.863553411157, 6115.908261376083, 6130.356312587203, 6149.29283132441, 6164.106204600353, 6169.629822210637, 5999.250093738283, 6175.320714721377, 6203.596766210182, 6207.4723648489935, 6215.906761398583, 5632.85917852057, 5726.112452569859, 5730.598858079936, 5731.713414329267, 5751.137329400582, 6233.2208473940755, 6261.622443062519, 6284.560080794392, 6301.023341382093, 6304.590201636879, 5671.007573674673, 5758.654480731169, 6087.969560152171, 6093.512518998469, 6096.268447786294, 6050.515958723292, 6127.705454835918, 6132.308783780411, 6138.539609529304, 6141.608749743955, 6124.958757011319, 6125.592594254237, 6128.787424850721, 6131.071888765667, 6134.263888333428, 5998.1602575639445, 6121.693915304217, 6121.7474480264555, 6136.079588061737, 6137.508999280047, 4570.97714511432, 4634.536546345338, 4637.234856248681, 4650.67445278834, 4678.11115887987, 4130.938035929464, 4133.132042271157, 4139.9793001035405, 4150.481189851269, 4160.667146628323, 5586.35756887955, 5688.772449102073, 5826.796062137805, 5831.7375718091935, 5833.649981001154, 6040.395960403925, 6081.6351018939, 6100.542459315569, 6102.786402475892, 6113.083037544315, 5756.107803290497, 5833.854153646108, 5843.970780146157, 5850.385709500526, 5858.2677165354335, 5193.039287731782, 5237.5286224239235, 5267.288915996368, 5283.973580132151, 5287.762050707727, 6313.684315784192, 6333.46165575922, 6338.492920566344, 6338.524610654219, 6338.587991780611, 6169.5064394848305, 6183.783567575113, 6205.100260462298, 6213.1922850029205, 6214.875702486015, 6156.415140561639, 6190.969045154746, 6292.465140463112, 6301.905471418024, 6333.809985700436, 6059.969700151471, 6061.484773794277, 6061.75506518259, 6083.148359229623, 6092.8781424372155, 6149.477294430024, 6149.84625384369, 6152.200213972155, 6153.169322141453, 6178.505719542516, 5783.103618939072, 5900.586958912835, 5948.226730525004, 6062.818115456544, 6076.817695469143, 5970.044792833126, 6003.369646187088, 6006.039033754579, 6020.909686354796, 6052.122442245938, 5601.663900166003, 5893.351731309638, 5894.322152952465, 5899.823005309848, 5900.64596124234, 5891.469767720754, 5897.498712609477, 6013.0, 6032.758689652364, 6043.486303664238, 5897.351291357862, 5938.138969849261, 5940.257467816523, 5941.22764040672, 5985.521158307412], "unit": "ms", "name": "dojo.html", "value": 5633.751880401715}, {"lowerIsBetter": false, "replicates": [640.5292110298932, 641.0448431836563, 645.6836150286359, 649.042662073442, 657.2146285189201, 42420.787896060516, 42953.57046429535, 42968.35547466783, 42980.0, 42994.35508467371, 47326.52673473273, 47861.7606911965, 47915.76042119785, 47956.28065579019, 48043.519564804345, 8179.182081791803, 8307.044689860653, 9246.537673116334, 9404.952975235114, 10779.78440431191, 10961.342319460819, 11038.0, 11069.446527673585, 11089.2237543372, 11091.112710983101, 11085.72285692856, 11204.103671706243, 11215.214934954556, 11218.0, 11228.831567526493, 11034.337939723642, 11072.667819965414, 11104.888951110506, 11133.888661113448, 11135.554577816878, 10825.458727063615, 10911.399873006982, 10938.507767150417, 10939.617113401073, 10944.343339399582, 43751.0, 44081.0, 44218.115637687064, 44354.33468497975, 44357.33463998043, 3779.263043706478, 3869.342211823997, 3890.435886796427, 4405.8237670493145, 4485.798139083749, 32935.50596741051, 33407.665923340945, 33444.83277583621, 33553.83223083893, 33723.325533489195, 3285.950710739341, 3342.5320455136302, 3404.8127352995575, 3426.777259478126, 3535.328287625343, 294102.0, 294526.0, 295572.0, 295693.0, 295779.0, 60730.39269607336, 61205.69397152986, 74327.0, 76385.61807190928, 76586.6170669143, 72003.0, 72024.63987680028, 72067.63966180135, 72081.0, 72137.27862721306, 19108.426747197605, 19210.711839322423, 19355.80644193568, 19395.903020484806, 19406.514837129183, 14864.777028344584, 14994.550163495112, 15009.774853377208, 15046.548603541693, 15049.473268435771, 23029.0, 23244.3026709199, 23248.302550923498, 23264.720440375604, 23270.418239544146, 8645.178708022728, 8912.688055918012, 9014.18872301496, 9038.276937844958, 9063.682771103111, 1048.6539441984128, 1101.2676570080966, 1114.2367478277406, 1155.62633753049, 1198.5086114692933, 30874.536881946788, 31860.840695796374, 31929.68070319267, 35051.82474087664, 36027.63972360295, 1132.105636547135, 1161.5353858456685, 1161.7444162284285, 1181.004103065816, 1235.4625737804083, 13676.794848077088, 15881.444149454714, 16133.838661613234, 16427.178641067898, 16859.15704214809, 341282.0, 343923.0, 345985.0, 347681.0, 348334.0, 59995.70002150049, 60755.0, 60777.69611152005, 60786.696066519384, 60886.391136088074, 1420.739403069957, 1427.6145440731152, 1530.0972426268333, 1556.6497538053848, 1580.6127498762876, 31794.523082153322, 32251.193720157185, 32442.188945276557, 34097.48853767146, 36030.279394412486, 55761.721191393786, 56298.43701563096, 56434.0, 56447.71776141093, 56499.0, 344323.0, 348126.0, 348231.0, 348384.0, 349524.0, 47815.76092119517, 48009.5199048005, 49122.263166051824, 49139.508604914925, 49472.50527494823, 50244.0, 50803.23795143002, 51048.23427648514, 51060.744696276284, 51270.230946535085, 42867.0, 43915.34126988034, 44007.559924400346, 44745.32882006707, 45043.099138017715, 46495.767521162175, 46501.0, 46760.298595521774, 46770.0, 48468.0, 41226.536781660856, 41520.169596607295, 41687.791561042, 42005.57994420139, 42133.57866421297, 36246.27507449889, 36388.0, 36578.26843463063, 37303.067423314635, 37545.62454375421, 27691.584626231026, 28241.15276541707, 28420.715792841806, 28455.43089138247, 28464.288392789516], "unit": "ms", "name": "ext.html", "value": 21577.784518684763}, {"lowerIsBetter": false, "replicates": [390.19814281651213, 394.78286942181813, 395.29242655646374, 398.59940759536664, 402.25984189092065, 13385.109591992641, 18196.545086372847, 19369.1283892225, 19584.216631334748, 20243.089060992254, 19452.12465439055, 19517.707234391495, 19518.31685890994, 19550.31573894914, 19552.41342759716, 5820.417958204188, 5944.702764861761, 5947.732352044169, 5953.077273022688, 5957.642541447505, 5238.13570760824, 5365.892682146355, 5893.204417403638, 5917.674527900964, 5935.169076329317, 3938.1336106056765, 3996.9400458993136, 5298.682079075248, 5303.893922121555, 5305.1776974569, 5284.973575132119, 5370.91943620846, 5433.402325744165, 5433.918491222635, 5444.564434845222, 4694.896699275668, 4695.483496815347, 4708.7174769513595, 4818.253170758503, 4831.444383895861, 15201.847981520265, 15318.617034574114, 15536.06783592988, 15547.378104875792, 15549.922250388789, 2264.9660255096187, 2297.7472478027403, 2353.576356255873, 2354.9646755298513, 2393.73668896423, 11162.218644694874, 11193.776124477465, 11218.831717524243, 11244.831327530093, 11517.0, 1851.3242666426793, 1891.1489829576608, 1901.1634880652468, 1906.5328994396323, 1946.6885298352195, 67370.0, 70173.2982670177, 72441.0, 72467.91298130533, 72801.27198727998, 7831.725889593837, 7890.408219383455, 7895.289423951872, 7957.840843183104, 8007.239312265326, 9620.71137865865, 9622.759431014141, 9627.90372096284, 9634.662786802428, 9644.0, 6237.656928868865, 6243.344448832807, 6409.230892292955, 6600.174978127734, 6601.0, 6664.733410663616, 6668.199816022108, 6688.498362622823, 6734.831629209211, 6738.764143254963, 3713.9071523212137, 3763.849446022183, 3769.1896242308035, 3786.1291902862495, 3794.924101517954, 1294.6698591859015, 1299.27240745183, 1303.8565757766637, 1304.302198323892, 1356.4913157565913, 503.62983207342575, 509.88017815813254, 525.1440152551316, 525.3590619444271, 549.4560385218684, 11973.820392694117, 12715.427805748579, 13037.217766934014, 13089.541866034833, 13217.14088584239, 547.1054825360565, 548.1613131908186, 549.5054450994137, 559.1975515135712, 562.4909456941474, 5566.721663916788, 6059.939400606026, 7356.668949897267, 7379.852402952019, 7557.130929943017, 612232.0, 683523.0, 686618.0, 688681.0, 694482.0, 79431.0, 81616.18383816205, 81773.0, 83217.58391208005, 84370.0, 587.6855882103055, 591.8757061017235, 592.3661682000219, 593.2169536212159, 595.1251660059705, 12375.381230938237, 12500.562480313145, 12552.87447125517, 12586.496540138658, 12612.180208286432, 39408.6059139405, 39807.60192398155, 40014.0, 40304.0, 40909.1818163641, 53216.0, 54506.454935450136, 54633.45366546284, 54633.72683136559, 54731.72634136804, 7628.122765881994, 7640.923590764021, 7726.381889448943, 7803.804904877424, 7811.648475818488, 13724.313784310545, 13767.105138165987, 13933.23367214813, 13934.790978135134, 14024.929875350557, 11806.527738890349, 11850.940745296219, 11965.162438629213, 12215.816762748389, 12382.318972456602, 11778.528858845893, 11881.5841445549, 12035.338056406981, 12171.45228464739, 12204.511819527119, 12187.085968552217, 12200.816987745013, 12356.876431235572, 12441.0, 12544.435500402322, 6736.93263067363, 7728.381729461742, 7738.806529836799, 7743.845123097394, 7780.533168009937, 11582.07343412508, 11849.703757406134, 11966.521339146684, 12140.696482587653, 12204.023678105908], "unit": "ms", "name": "jquery.html", "value": 7892.394213293002}, {"lowerIsBetter": false, "replicates": [1553.1690545558129, 1778.422012845825, 1798.820117988201, 1855.7680289963762, 1880.3982725527828, 8410.747677569667, 8613.44012639178, 8615.39692221544, 8617.784555386103, 8634.050254472018, 7260.310270524292, 7442.292982166699, 7975.76072717818, 8148.755537333875, 8509.489430634167, 2407.1695265133544, 2409.1929203716777, 2434.6834911461483, 2436.5492383908945, 2444.559979203743, 2169.034779523113, 2458.864762438065, 2465.6917885264343, 2491.862947537885, 2500.949981000379, 2143.07847625521, 2371.92884213473, 2412.8914198861007, 2415.6497307890345, 2415.7825795678386, 2375.2636682628354, 2410.0239403041796, 2412.5054363855365, 2414.1429792423614, 2416.734159242482, 2391.6053851114543, 2413.9517209655883, 2472.320111969205, 2493.5012997400413, 2494.0, 8497.405181637288, 8563.529005904673, 8567.31461483086, 8572.228499435081, 8573.399862009663, 5183.3002544656365, 5259.526642602184, 5266.867623460936, 5266.8683282917855, 5270.367555893279, 3295.5221492883397, 3301.273719781644, 3331.700146986783, 3389.1018879996823, 3419.384510788057, 1081.7836432713489, 1102.448775612194, 1115.2806439846254, 1120.5517792882831, 1128.463979609687, 11914.523419063227, 11924.940375298069, 11961.581344652983, 11978.58074967372, 11985.520579176822, 4730.929036064462, 4747.667663263607, 4812.639052071108, 4813.085513752376, 4850.6362022847725, 10042.598296068221, 10086.193104551618, 10091.041351071637, 10101.444420556792, 10111.646092386878, 5262.394824595221, 5263.499967503081, 5265.341832270966, 5265.447128051576, 5273.3935597406025, 4795.064962332312, 4812.639052071108, 4815.277708343777, 4817.301491283744, 4819.903601928012, 4076.0, 4117.485314335708, 4117.711760176759, 4155.148194620096, 4162.375643653416, 1237.8638349781518, 1262.8042653388738, 1291.122037014839, 1310.698539335939, 1313.1136482874213, 1077.7413420778958, 1148.322489731046, 1152.8962393384468, 1367.4530187924909, 1443.4370595467776, 7925.405594580429, 7985.600719963979, 7993.080795708568, 8063.153368896299, 8131.0, 1218.8842060004463, 1219.5975328141692, 1219.7499512600073, 1233.2908577567755, 1241.8016613967566, 6221.96889015552, 6304.464120549713, 6322.209723784527, 6342.714577844008, 6383.840403989844, 10208.948955255177, 10445.059944605022, 10456.424896630606, 10456.843147352796, 10475.580976760844, 10095.899041009643, 10341.534630941625, 10361.326513776581, 10368.222383321281, 10376.273660843668, 7933.801654958672, 7968.282854543119, 7975.202479751978, 7977.082635496876, 7989.320907722792, 8051.355891528547, 8090.555019473985, 8147.185281471805, 8178.591070446335, 8213.178682131738, 9126.45241285525, 9233.307501937246, 9260.39807412516, 9267.351285410226, 9267.67563135287, 9350.0, 9459.0, 9487.0, 9564.0, 9580.76048098803, 7682.769516914502, 7918.722844700408, 7991.400644951768, 7994.640241189277, 8000.799980000546, 7991.560464174526, 8037.156098609562, 8058.153893841297, 8059.194080591894, 8059.758207253792, 6386.584871983307, 7256.7460138896995, 7354.6690398931105, 7410.851782964418, 7682.347000504796, 7502.512336698097, 7515.661795219119, 7600.581967991813, 7609.581473018816, 7626.466147369631, 7727.995360603086, 7758.612069396395, 7758.9612051939375, 7771.145174030739, 7773.144954055162, 6876.518643694893, 7103.183133939663, 7150.928490715234, 7191.568505889663, 7192.568445893263, 7310.488265821342, 7342.229065948212, 7349.70601175947, 7351.301626345488, 7351.77944661661], "unit": "ms", "name": "mootools.html", "value": 4923.046076970771}, {"lowerIsBetter": false, "replicates": [370.0895796341001, 371.48363774353646, 374.72644969172484, 377.8696349759332, 378.34923930838954, 5847.473727364536, 5921.763129474826, 5926.644401335918, 5963.135345374918, 5976.163337132794, 5793.942060579393, 5840.06558950568, 5874.706264686759, 5891.823245302637, 5896.941030589693, 4665.276882083265, 4667.276572131325, 4668.463126740435, 4675.696079754822, 4677.766111694419, 4531.77341132942, 4540.250858608342, 4602.769861506928, 4666.743329116897, 4677.321788340706, 4583.770811459431, 4610.815567377318, 4614.700044497097, 4634.791434385461, 4635.791389387468, 4582.33556134362, 4591.517890621503, 4592.380028696117, 4595.931061034087, 4608.677392582521, 4069.674426045909, 4069.8779036628653, 4074.9185016299803, 4080.4287399764057, 4097.651699605537, 5539.473749993744, 5645.802396916089, 5733.8566535836935, 5783.681897495636, 5787.913181302284, 5469.206964990094, 5470.0, 5475.479829416239, 5475.72621368934, 5478.424765399615, 6047.78832740852, 6353.8411539711415, 6359.268684101295, 6361.141245931786, 6365.395287447685, 4469.10617876426, 4501.099780044005, 4553.180427523044, 4558.61251793598, 4562.1560011398, 6442.291347951722, 6460.709268082947, 6461.12774775404, 6469.288378278386, 6475.643839588821, 4665.4168228971375, 4669.906601868011, 4670.346151538787, 4670.95329046712, 4671.976640116845, 4534.410526631516, 4602.608778253819, 4616.676832621752, 4733.644976626767, 4744.525547445228, 5270.1040823060175, 5274.92087618686, 5276.15581506957, 5276.472352764769, 5279.498447647467, 6283.088952102011, 6283.874322513524, 6287.779927702509, 6288.465480434123, 6292.244930608356, 1667.3497336038954, 1678.639092595098, 1685.1405783050732, 1689.4171510828744, 1689.9831001690072, 1138.1122724275078, 1140.2075557487633, 1140.441183819939, 1155.664857191405, 1172.5075468303332, 6182.938170618237, 6183.567150299526, 6188.1027251048345, 6192.59748116371, 6194.473469755031, 6589.604623722496, 6590.242122155918, 6591.802245932629, 6596.670166491656, 6596.96701516499, 6057.061155520902, 6174.845628859224, 6177.81466556004, 6179.721912513947, 6187.597806142587, 3608.0438683748825, 3640.6723394894057, 3643.417053271464, 3643.6720695137565, 3646.033801042726, 6739.393454589111, 6745.392914637608, 6749.493787965922, 6751.156105486814, 6752.561083529555, 6627.37039981201, 6720.092787473726, 6723.0, 6724.361185687352, 6724.562903411263, 6699.263081061079, 6699.564528305645, 6713.26154123046, 6719.42884854783, 6746.561473504206, 6289.96855015722, 6368.267649220214, 6459.128017717736, 6515.869682606416, 6536.7712130075215, 6449.677516124081, 6457.935420645921, 6462.3537646235, 6465.191851018622, 6473.449756770538, 6666.733330666719, 6676.732930682719, 6677.532572719863, 6678.165229346332, 6701.899471507833, 4507.75207363585, 4571.794269257958, 4580.358749774968, 4629.213033784332, 4642.767861606973, 6428.4214420702365, 6441.742330306735, 6640.9335906642245, 6649.900251496329, 6667.599944003376, 6252.3122456530655, 6254.530910181846, 6448.967755161194, 6452.257990330984, 6472.546921715435, 6193.597416167935, 6223.222097237845, 6312.589681670676, 6481.902771458337, 6482.805515834532, 6338.429541341301, 6347.650879201504, 6358.618482891041, 6376.744930202925, 6380.202474690664, 6437.678116094083, 6474.125992990886, 6498.480121590356, 6501.967490162708, 6508.316626754312, 6228.159198508325, 6312.116303717391, 6348.873022539431, 6353.078803573363, 6359.523035772429], "unit": "ms", "name": "prototype.html", "value": 4846.649743775652}, {"lowerIsBetter": false, "replicates": [829.9502029878208, 871.8910136232971, 878.8813510176125, 972.7130496503531, 975.3903810118676, 14700.70598588029, 15342.769858452131, 15350.46273380432, 15368.923155384235, 15371.692566148684, 14199.929000354983, 14216.715665686681, 14304.856951430482, 14356.210408427532, 14378.49675261366, 764.1365257259289, 766.7814672818248, 933.701215611003, 951.1059603972278, 951.6764300137952, 908.0737647599477, 915.3546749541557, 916.326500022483, 916.6974898283551, 917.0554329041065, 971.2958105373618, 974.3131092579714, 980.9607615695363, 981.3228872078279, 998.5955687946393, 810.6797814863125, 812.0661239574478, 966.8211380894487, 970.1365784451843, 997.5560875410446, 706.6184260499324, 713.165596252386, 713.5825542057902, 720.9243029481934, 733.3986131372277, 14834.703305933821, 14856.405743770236, 14881.10713357202, 14927.701445971019, 14965.92517037408, 13961.232132232723, 14112.92943535286, 14115.153090814481, 14193.07744996572, 14218.928905355408, 11017.724556886062, 11189.608363707313, 11303.208775385727, 11327.32036077838, 11421.714457138554, 3888.591697871739, 3941.4679018332445, 3950.7036972227197, 3960.801959902022, 4012.7592344459426, 50560.49439505632, 53953.0, 54000.0, 54674.0, 54786.17820732732, 11204.215704900578, 11230.213885028134, 11278.436078196057, 11302.265352752045, 11521.423928803526, 20805.687914681293, 20843.270485533238, 20848.0, 20887.79112208889, 20891.164353425997, 8196.0, 8336.124706905808, 8358.791030224293, 8422.452540584842, 8442.704505342284, 4359.12817436514, 4425.756583387944, 4449.1101779644205, 4488.955110448854, 4509.075639493896, 3310.966890331114, 3329.900102996914, 3340.1816554944194, 3349.129226401153, 3358.227607650254, 497.5024975024975, 515.5488947171225, 518.3106468397023, 520.2534363188836, 521.3977855576815, 554.287740253772, 572.2189211725964, 582.5659883386904, 604.1119554255238, 610.1640752169557, 8981.640734370683, 9018.188363047357, 9087.909120908706, 9318.813623727623, 9495.620175193053, 617.1514168018974, 633.7084940927225, 638.4541217259176, 642.1395330257477, 657.9572327798678, 4130.339145736668, 4135.276326642826, 4149.958500415018, 4159.085001299709, 4225.915481690349, 69621.30378696148, 70295.64852175809, 70618.29381706117, 71114.2888571118, 71750.0, 60541.69729151326, 61331.6933415339, 61956.0, 61992.0, 62283.37716622867, 54561.0, 54747.45252547503, 54806.0, 55372.16941745877, 55558.444415556136, 8339.874901876356, 8418.452800567944, 8697.434666746642, 8721.258693011165, 8797.164269394398, 39681.0, 40053.599464006154, 40352.39471407873, 41115.0, 41789.0, 50604.0, 51463.0, 51562.74218629033, 52126.21810672919, 52165.0, 8016.559089250147, 8143.144969778088, 8220.876686849582, 8244.587770611566, 8316.334693224648, 11404.315741055563, 11461.598844040418, 11693.123015774021, 11837.348945807718, 11920.940395297968, 10407.219458540489, 10444.895551044696, 10491.370517768959, 10543.0, 10789.460526973462, 9536.904630953879, 9669.758256043655, 9836.3606365586, 9940.0, 9951.701448956543, 10606.416647084176, 10678.252522323673, 10681.412522311348, 10845.186611004365, 11086.944565277123, 10395.0, 10541.367517948947, 10542.209334299805, 10756.784864302514, 10843.132549396187, 10015.599376024877, 10359.326643768432, 10377.169826413718, 10377.481125943521, 10410.219233557666], "unit": "ms", "name": "yui.html", "value": 6700.203053751111}], "name": "dromaeo_css", "value": 7316.48680429073}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 112.0, 103.0, 109.0, 112.0, 109.0, 100.0, 110.0, 99.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [146.0, 122.0, 147.0, 162.0, 131.0, 143.0, 149.0, 122.0, 178.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.3}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 160.0, 160.0, 159.0, 160.0, 160.0, 163.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 70.0, 146.0, 71.0, 71.0, 71.0, 71.0, 155.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.7}, {"lowerIsBetter": true, "replicates": [88.0, 89.0, 132.0, 89.0, 90.0, 89.0, 137.0, 88.0, 90.0, 140.0], "unit": "ms", "name": "audio-oscillator", "value": 103.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 147.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [53.0, 55.0, 54.0, 54.0, 55.0, 55.0, 55.0, 65.0, 53.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 55.4}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 62.0, 62.0, 62.0, 63.0, 63.0, 62.0, 66.0, 62.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.8}, {"lowerIsBetter": true, "replicates": [78.0, 77.0, 79.0, 77.0, 78.0, 77.0, 87.0, 78.0, 80.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.0}, {"lowerIsBetter": true, "replicates": [117.0, 123.0, 123.0, 113.0, 109.0, 116.0, 114.0, 111.0, 121.0, 109.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.6}, {"lowerIsBetter": true, "replicates": [135.0, 135.0, 136.0, 134.0, 127.0, 134.0, 135.0, 135.0, 134.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 134.0}, {"lowerIsBetter": true, "replicates": [60.0, 63.0, 64.0, 60.0, 60.0, 61.0, 60.0, 61.0, 61.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.1}], "name": "kraken", "value": 1458.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.90755613833798], "unit": "score", "name": "Richards", "value": 215.377502}, {"lowerIsBetter": false, "replicates": [165.7000828500414], "unit": "score", "name": "DeltaBlue", "value": 399.02212999999995}, {"lowerIsBetter": false, "replicates": [279.9552071668533], "unit": "score", "name": "Encrypt", "value": 950.7985320000004}, {"lowerIsBetter": false, "replicates": [5010.0], "unit": "score", "name": "Decrypt", "value": 53.12994011976049}, {"lowerIsBetter": false, "replicates": [940.7337723424271], "unit": "score", "name": "RayTrace", "value": 786.6083070000002}, {"lowerIsBetter": false, "replicates": [550.6607929515418], "unit": "score", "name": "Earley", "value": 1210.2968080000003}, {"lowerIsBetter": false, "replicates": [10350.515463917525], "unit": "score", "name": "Boyer", "value": 64.38935358565732}, {"lowerIsBetter": false, "replicates": [26000.0], "unit": "score", "name": "RegExp", "value": 35.037884615384634}, {"lowerIsBetter": false, "replicates": [532.197977647685], "unit": "score", "name": "Splay", "value": 153.12158900000006}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23229.242600754686}]} 18:12:31 INFO - RETURN: dromaeo_css: 7316.49 18:12:31 INFO - RETURN: kraken: 1458.10 18:12:31 INFO - RETURN: v8_7: 23229.24 18:12:31 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23229.24"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1458.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7316.49"}}} 18:12:32 INFO - Return code: 0 18:12:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 18:12:32 INFO - # TBPL SUCCESS # 18:12:32 INFO - Running post-action listener: _resource_record_post_action 18:12:32 INFO - Running post-run listener: _resource_record_post_run 18:12:33 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 0; Write bytes: 365572096; Read time: 0; Write time: 190624 18:12:33 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 99553280; Read time: 0; Write time: 53404 18:12:33 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 0; Write bytes: 261812224; Read time: 0; Write time: 131468 18:12:33 INFO - Running post-run listener: _upload_blobber_files 18:12:33 INFO - Blob upload gear active. 18:12:33 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:12:33 INFO - Copying logs to upload dir... 18:12:33 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.798111 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-01-05 18:12:32.848448) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-05 18:12:32.851843) ========= 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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009965 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452036692/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 2016-01-05 18:12:32.903626) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 18:12:32.903959) ========= 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=aa08bb06ff6271ef470a76a4000001be-1452044949.699685-664740164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 18:12:32.953605) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 18:12:32.953919) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 18:12:32.954234) ========= ========= Total master_lag: 0.22 =========