builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1450267786.61 results: success (0) buildid: 20151214205110 builduid: 489f3942ee5c444ea53c5ff4fc03e011 revision: d1a4b331d80b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.615484) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.615902) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.616305) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005301 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.655472) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.655787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.680305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.680592) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.737757) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.738043) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.738371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.738666) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-16 04:09:47-- 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% 223M=0s 2015-12-16 04:09:47 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104554 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.865534) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.866017) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022386 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.921432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:46.921754) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1a4b331d80b --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 d1a4b331d80b --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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-16 04:09:47,827 Setting DEBUG logging. 2015-12-16 04:09:47,827 attempt 1/10 2015-12-16 04:09:47,827 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d1a4b331d80b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-16 04:09:48,344 unpacking tar archive at: mozilla-beta-d1a4b331d80b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.797282 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.737848) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.741185) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.754955) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.755410) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-16 04:09:47.755844) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-12-16 04:09:47.756182) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --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', '--system-bits', '64', '--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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:09:48 INFO - MultiFileLogger online at 20151216 04:09:48 in /builds/slave/test-pgo 04:09:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:09:48 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 04:09:48 INFO - {'append_to_log': False, 04:09:48 INFO - 'base_work_dir': '/builds/slave/test-pgo', 04:09:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:09:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 04:09:48 INFO - 'branch': 'Mozilla-Beta', 04:09:48 INFO - 'buildbot_json_path': 'buildprops.json', 04:09:48 INFO - 'config_files': ('talos/linux_config.py',), 04:09:48 INFO - 'default_actions': ('clobber', 04:09:48 INFO - 'read-buildbot-config', 04:09:48 INFO - 'download-and-extract', 04:09:48 INFO - 'populate-webroot', 04:09:48 INFO - 'create-virtualenv', 04:09:48 INFO - 'install', 04:09:48 INFO - 'run-tests'), 04:09:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:09:48 INFO - 'download_minidump_stackwalk': True, 04:09:48 INFO - 'download_symbols': 'ondemand', 04:09:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:09:48 INFO - 'tooltool.py': '/tools/tooltool.py', 04:09:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:09:48 INFO - '/tools/misc-python/virtualenv.py')}, 04:09:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:09:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:09:48 INFO - 'installer_path': 'installer.exe', 04:09:48 INFO - 'log_level': 'info', 04:09:48 INFO - 'log_name': 'talos', 04:09:48 INFO - 'log_to_console': True, 04:09:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:09:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:09:48 INFO - 'opt_config_files': (), 04:09:48 INFO - 'pip_index': False, 04:09:48 INFO - 'sps_profile': False, 04:09:48 INFO - 'sps_profile_interval': 0, 04:09:48 INFO - 'suite': 'dromaeojs', 04:09:48 INFO - 'system_bits': '64', 04:09:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:09:48 INFO - 'title': 'talos-linux64-ix-035', 04:09:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:09:48 INFO - 'use_talos_json': True, 04:09:48 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 04:09:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:09:48 INFO - 'work_dir': 'build'} 04:09:48 INFO - ##### 04:09:48 INFO - ##### Running clobber step. 04:09:48 INFO - ##### 04:09:48 INFO - Running pre-action listener: _resource_record_pre_action 04:09:48 INFO - Running main action method: clobber 04:09:48 INFO - rmtree: /builds/slave/test-pgo/build 04:09:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 04:09:49 INFO - Running post-action listener: _resource_record_post_action 04:09:49 INFO - ##### 04:09:49 INFO - ##### Running read-buildbot-config step. 04:09:49 INFO - ##### 04:09:49 INFO - Running pre-action listener: _resource_record_pre_action 04:09:49 INFO - Running main action method: read_buildbot_config 04:09:49 INFO - Using buildbot properties: 04:09:49 INFO - { 04:09:49 INFO - "properties": { 04:09:49 INFO - "buildnumber": 75, 04:09:49 INFO - "product": "firefox", 04:09:49 INFO - "script_repo_revision": "production", 04:09:49 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 04:09:49 INFO - "repository": "", 04:09:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 04:09:49 INFO - "buildid": "20151214205110", 04:09:49 INFO - "slavename": "talos-linux64-ix-035", 04:09:49 INFO - "pgo_build": "True", 04:09:49 INFO - "basedir": "/builds/slave/test-pgo", 04:09:49 INFO - "project": "", 04:09:49 INFO - "platform": "ubuntu64_hw", 04:09:49 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:09:49 INFO - "slavebuilddir": "test", 04:09:49 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 04:09:49 INFO - "branch": "mozilla-beta", 04:09:49 INFO - "repo_path": "releases/mozilla-beta", 04:09:49 INFO - "stage_platform": "linux64-pgo", 04:09:49 INFO - "builduid": "489f3942ee5c444ea53c5ff4fc03e011", 04:09:49 INFO - "revision": "d1a4b331d80b" 04:09:49 INFO - }, 04:09:49 INFO - "sourcestamp": { 04:09:49 INFO - "repository": "", 04:09:49 INFO - "hasPatch": false, 04:09:49 INFO - "project": "", 04:09:49 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 04:09:49 INFO - "changes": [ 04:09:49 INFO - { 04:09:49 INFO - "category": null, 04:09:49 INFO - "files": [ 04:09:49 INFO - { 04:09:49 INFO - "url": null, 04:09:49 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2" 04:09:49 INFO - } 04:09:49 INFO - ], 04:09:49 INFO - "repository": "", 04:09:49 INFO - "rev": "d1a4b331d80b", 04:09:49 INFO - "who": "sendchange", 04:09:49 INFO - "when": 1450163443, 04:09:49 INFO - "number": 7361158, 04:09:49 INFO - "comments": "Bug 1232466 - Hack around use of re.sub(..., flags=). r=me a=bustage", 04:09:49 INFO - "project": "", 04:09:49 INFO - "at": "Mon 14 Dec 2015 23:10:43", 04:09:49 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 04:09:49 INFO - "revlink": "", 04:09:49 INFO - "properties": [ 04:09:49 INFO - [ 04:09:49 INFO - "buildid", 04:09:49 INFO - "20151214205110", 04:09:49 INFO - "Change" 04:09:49 INFO - ], 04:09:49 INFO - [ 04:09:49 INFO - "builduid", 04:09:49 INFO - "489f3942ee5c444ea53c5ff4fc03e011", 04:09:49 INFO - "Change" 04:09:49 INFO - ], 04:09:49 INFO - [ 04:09:49 INFO - "pgo_build", 04:09:49 INFO - "True", 04:09:49 INFO - "Change" 04:09:49 INFO - ] 04:09:49 INFO - ], 04:09:49 INFO - "revision": "d1a4b331d80b" 04:09:49 INFO - } 04:09:49 INFO - ], 04:09:49 INFO - "revision": "d1a4b331d80b" 04:09:49 INFO - } 04:09:49 INFO - } 04:09:49 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2. 04:09:49 INFO - Running post-action listener: _resource_record_post_action 04:09:49 INFO - ##### 04:09:49 INFO - ##### Running download-and-extract step. 04:09:49 INFO - ##### 04:09:49 INFO - Running pre-action listener: _resource_record_pre_action 04:09:49 INFO - Running main action method: download_and_extract 04:09:49 INFO - mkdir: /builds/slave/test-pgo/build/tests 04:09: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')]} 04:09:49 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json 04:09:49 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 04:09:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 04:09:49 INFO - Downloaded 1270 bytes. 04:09:49 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 04:09:49 INFO - Using the following test package requirements: 04:09:49 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:09:49 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:49 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 04:09:49 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:49 INFO - u'jsshell-linux-x86_64.zip'], 04:09:49 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:49 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 04:09:49 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:09:49 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:49 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 04:09:49 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:49 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 04:09:49 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:49 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 04:09:49 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 04:09:49 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 04:09:49 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 04:09:49 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:09: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')]} 04:09:49 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:09:49 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:09:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:09:58 INFO - Downloaded 22571596 bytes. 04:09:58 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 04:09:58 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:09:58 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 04:09:59 INFO - Return code: 0 04:09:59 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 04:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:09:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:09:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:00 INFO - Downloaded 22571596 bytes. 04:10:00 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 04:10:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 04:10:00 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 04:10:01 INFO - Return code: 0 04:10:01 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')]} 04:10:01 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 04:10:01 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:05 INFO - Downloaded 11131970 bytes. 04:10:05 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 04:10:05 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 04:10:05 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 04:10:05 INFO - Return code: 0 04:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:10:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/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 04:10:14 INFO - Downloaded 56638018 bytes. 04:10:14 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:10:14 INFO - mkdir: /builds/slave/test-pgo/properties 04:10:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 04:10:14 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 04:10:14 INFO - Contents: 04:10:14 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 04:10:14 INFO - Running post-action listener: _resource_record_post_action 04:10:14 INFO - Running post-action listener: set_extra_try_arguments 04:10:14 INFO - ##### 04:10:14 INFO - ##### Running populate-webroot step. 04:10:14 INFO - ##### 04:10:14 INFO - Running pre-action listener: _resource_record_pre_action 04:10:14 INFO - Running main action method: populate_webroot 04:10:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:10:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:10:14 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 04:10:14 INFO - '../fennec_ids.txt'], 04:10:14 INFO - 'tests': ['tcheck2']}, 04:10:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:10:14 INFO - '--tppagecycles', 04:10:14 INFO - '7'], 04:10:14 INFO - 'tests': ['tsvgm']}}, 04:10:14 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:10:14 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:10:14 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:10:14 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:10:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'tests': ['damp', 'tps']}, 04:10:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['damp', 'tps']}, 04:10:14 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:10:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:10:14 INFO - 'other': {'tests': ['a11yr', 04:10:14 INFO - 'ts_paint', 04:10:14 INFO - 'tpaint', 04:10:14 INFO - 'sessionrestore', 04:10:14 INFO - 'sessionrestore_no_auto_restore']}, 04:10:14 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['a11yr', 04:10:14 INFO - 'ts_paint', 04:10:14 INFO - 'tpaint', 04:10:14 INFO - 'sessionrestore', 04:10:14 INFO - 'sessionrestore_no_auto_restore']}, 04:10:14 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['a11yr', 04:10:14 INFO - 'ts_paint', 04:10:14 INFO - 'tpaint', 04:10:14 INFO - 'sessionrestore', 04:10:14 INFO - 'sessionrestore_no_auto_restore']}, 04:10:14 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['a11yr', 04:10:14 INFO - 'ts_paint', 04:10:14 INFO - 'tpaint', 04:10:14 INFO - 'sessionrestore', 04:10:14 INFO - 'sessionrestore_no_auto_restore']}, 04:10:14 INFO - 'other_l64': {'tests': ['a11yr', 04:10:14 INFO - 'ts_paint', 04:10:14 INFO - 'tpaint', 04:10:14 INFO - 'sessionrestore', 04:10:14 INFO - 'sessionrestore_no_auto_restore']}, 04:10:14 INFO - 'other_nol64': {'tests': ['a11yr', 04:10:14 INFO - 'ts_paint', 04:10:14 INFO - 'tpaint', 04:10:14 INFO - 'sessionrestore', 04:10:14 INFO - 'sessionrestore_no_auto_restore']}, 04:10:14 INFO - 'svgr': {'tests': ['tsvgx', 04:10:14 INFO - 'tsvgr_opacity', 04:10:14 INFO - 'tart', 04:10:14 INFO - 'tscrollx', 04:10:14 INFO - 'cart']}, 04:10:14 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['tsvgx', 04:10:14 INFO - 'tsvgr_opacity', 04:10:14 INFO - 'tart', 04:10:14 INFO - 'tscrollx', 04:10:14 INFO - 'cart']}, 04:10:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'tests': ['tp5o']}, 04:10:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'talos_options': ['--e10s'], 04:10:14 INFO - 'tests': ['tp5o']}, 04:10:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'talos_options': ['--xperf_path', 04:10:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:14 INFO - 'tests': ['tp5n']}, 04:10:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:14 INFO - 'talos_options': ['--e10s', 04:10:14 INFO - '--xperf_path', 04:10:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:14 INFO - 'tests': ['tp5n']}}, 04:10:14 INFO - 'talos.zip': {'path': '', 04:10:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:10:14 INFO - Running post-action listener: _resource_record_post_action 04:10:14 INFO - ##### 04:10:14 INFO - ##### Running create-virtualenv step. 04:10:14 INFO - ##### 04:10:14 INFO - Running pre-action listener: _resource_record_pre_action 04:10:14 INFO - Running main action method: create_virtualenv 04:10:14 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 04:10:14 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 04:10:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 04:10:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:10:14 INFO - Using real prefix '/usr' 04:10:14 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 04:10:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:10:16 INFO - Installing pip.................done. 04:10:16 INFO - Return code: 0 04:10:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 04:10:16 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')]} 04:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:16 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')]} 04:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:16 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:16 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 04:10:16 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 04:10:16 INFO - Using env: {'DISPLAY': ':0', 04:10:16 INFO - 'HOME': '/home/cltbld', 04:10:16 INFO - 'LANG': 'en_US.UTF-8', 04:10:16 INFO - 'LANGUAGE': 'en_US:en', 04:10:16 INFO - 'LOGNAME': 'cltbld', 04:10:16 INFO - 'MAIL': '/var/mail/cltbld', 04:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:16 INFO - 'MOZ_NO_REMOTE': '1', 04:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:16 INFO - 'NO_EM_RESTART': '1', 04:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:16 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:16 INFO - 'SHELL': '/bin/bash', 04:10:16 INFO - 'SHLVL': '1', 04:10:16 INFO - 'TERM': 'linux', 04:10:16 INFO - 'TMOUT': '86400', 04:10:16 INFO - 'USER': 'cltbld', 04:10:16 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:16 INFO - '_': '/tools/buildbot/bin/python'} 04:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:16 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 04:10:16 INFO - Cleaning up... 04:10:16 INFO - Return code: 0 04:10:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 04:10:16 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')]} 04:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:16 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')]} 04:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:16 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:16 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 04:10:16 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 04:10:16 INFO - Using env: {'DISPLAY': ':0', 04:10:16 INFO - 'HOME': '/home/cltbld', 04:10:16 INFO - 'LANG': 'en_US.UTF-8', 04:10:16 INFO - 'LANGUAGE': 'en_US:en', 04:10:16 INFO - 'LOGNAME': 'cltbld', 04:10:16 INFO - 'MAIL': '/var/mail/cltbld', 04:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:16 INFO - 'MOZ_NO_REMOTE': '1', 04:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:16 INFO - 'NO_EM_RESTART': '1', 04:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:16 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:16 INFO - 'SHELL': '/bin/bash', 04:10:16 INFO - 'SHLVL': '1', 04:10:16 INFO - 'TERM': 'linux', 04:10:16 INFO - 'TMOUT': '86400', 04:10:16 INFO - 'USER': 'cltbld', 04:10:16 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:16 INFO - '_': '/tools/buildbot/bin/python'} 04:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:17 INFO - Downloading/unpacking psutil>=0.7.1 04:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:18 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 04:10:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:10:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 04:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:18 INFO - Installing collected packages: psutil 04:10:18 INFO - Running setup.py install for psutil 04:10:18 INFO - building 'psutil._psutil_linux' extension 04:10:18 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 04:10:19 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 04:10:19 INFO - building 'psutil._psutil_posix' extension 04:10:19 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 04:10:19 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 04:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:19 INFO - Successfully installed psutil 04:10:19 INFO - Cleaning up... 04:10:19 INFO - Return code: 0 04:10:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 04:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:19 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:19 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 04:10:19 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 04:10:19 INFO - Using env: {'DISPLAY': ':0', 04:10:19 INFO - 'HOME': '/home/cltbld', 04:10:19 INFO - 'LANG': 'en_US.UTF-8', 04:10:19 INFO - 'LANGUAGE': 'en_US:en', 04:10:19 INFO - 'LOGNAME': 'cltbld', 04:10:19 INFO - 'MAIL': '/var/mail/cltbld', 04:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:19 INFO - 'MOZ_NO_REMOTE': '1', 04:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:19 INFO - 'NO_EM_RESTART': '1', 04:10:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:19 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:19 INFO - 'SHELL': '/bin/bash', 04:10:19 INFO - 'SHLVL': '1', 04:10:19 INFO - 'TERM': 'linux', 04:10:19 INFO - 'TMOUT': '86400', 04:10:19 INFO - 'USER': 'cltbld', 04:10:19 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:19 INFO - '_': '/tools/buildbot/bin/python'} 04:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:10:19 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 04:10:19 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 04:10:19 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 04:10:19 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 04:10:19 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 04:10:19 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 04:10:21 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:10:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:10:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:10:21 INFO - Installing collected packages: mozsystemmonitor 04:10:21 INFO - Running setup.py install for mozsystemmonitor 04:10:21 INFO - Successfully installed mozsystemmonitor 04:10:21 INFO - Cleaning up... 04:10:21 INFO - Return code: 0 04:10:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 04:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:21 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:21 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 04:10:21 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 04:10:21 INFO - Using env: {'DISPLAY': ':0', 04:10:21 INFO - 'HOME': '/home/cltbld', 04:10:21 INFO - 'LANG': 'en_US.UTF-8', 04:10:21 INFO - 'LANGUAGE': 'en_US:en', 04:10:21 INFO - 'LOGNAME': 'cltbld', 04:10:21 INFO - 'MAIL': '/var/mail/cltbld', 04:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:21 INFO - 'MOZ_NO_REMOTE': '1', 04:10:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:21 INFO - 'NO_EM_RESTART': '1', 04:10:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:21 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:21 INFO - 'SHELL': '/bin/bash', 04:10:21 INFO - 'SHLVL': '1', 04:10:21 INFO - 'TERM': 'linux', 04:10:21 INFO - 'TMOUT': '86400', 04:10:21 INFO - 'USER': 'cltbld', 04:10:21 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:21 INFO - '_': '/tools/buildbot/bin/python'} 04:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:21 INFO - Downloading/unpacking blobuploader==1.2.4 04:10:21 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 04:10:21 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 04:10:21 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 04:10:21 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 04:10:21 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 04:10:21 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 04:10:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:10:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:10:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:10:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:10:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 04:10:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:24 INFO - Downloading docopt-0.6.1.tar.gz 04:10:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:10:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 04:10:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:10:24 INFO - Running setup.py install for blobuploader 04:10:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:10:24 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 04:10:24 INFO - Running setup.py install for requests 04:10:24 INFO - Running setup.py install for docopt 04:10:24 INFO - Successfully installed blobuploader requests docopt 04:10:24 INFO - Cleaning up... 04:10:24 INFO - Return code: 0 04:10:24 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:10: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')]} 04:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10: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')]} 04:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:24 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:24 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 04:10:24 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 04:10:24 INFO - Using env: {'DISPLAY': ':0', 04:10:24 INFO - 'HOME': '/home/cltbld', 04:10:24 INFO - 'LANG': 'en_US.UTF-8', 04:10:24 INFO - 'LANGUAGE': 'en_US:en', 04:10:24 INFO - 'LOGNAME': 'cltbld', 04:10:24 INFO - 'MAIL': '/var/mail/cltbld', 04:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:24 INFO - 'MOZ_NO_REMOTE': '1', 04:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:24 INFO - 'NO_EM_RESTART': '1', 04:10:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:24 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:24 INFO - 'SHELL': '/bin/bash', 04:10:24 INFO - 'SHLVL': '1', 04:10:24 INFO - 'TERM': 'linux', 04:10:24 INFO - 'TMOUT': '86400', 04:10:24 INFO - 'USER': 'cltbld', 04:10:24 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:24 INFO - '_': '/tools/buildbot/bin/python'} 04:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:24 INFO - Running setup.py (path:/tmp/pip-XFRkXV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:24 INFO - Running setup.py (path:/tmp/pip-07xAUV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:25 INFO - Running setup.py (path:/tmp/pip-ZQMqbs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:25 INFO - Running setup.py (path:/tmp/pip-8unYPU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:25 INFO - Running setup.py (path:/tmp/pip-CkAz41-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:25 INFO - Running setup.py (path:/tmp/pip-MiU9Xd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:25 INFO - Running setup.py (path:/tmp/pip-Vx5YBr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:25 INFO - Running setup.py (path:/tmp/pip-QdYjQD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:25 INFO - Running setup.py (path:/tmp/pip-5SvBB1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:25 INFO - Running setup.py (path:/tmp/pip-sC5meq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:25 INFO - Running setup.py (path:/tmp/pip-NlpLlq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:25 INFO - Running setup.py (path:/tmp/pip-ywN9VL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:25 INFO - Running setup.py (path:/tmp/pip-AJqHBW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:25 INFO - Running setup.py (path:/tmp/pip-wysbjV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:25 INFO - Running setup.py (path:/tmp/pip-q_zOYd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:25 INFO - Running setup.py (path:/tmp/pip-KthSVP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:26 INFO - Running setup.py (path:/tmp/pip-c4cp5u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:10:26 INFO - Running setup.py install for manifestparser 04:10:26 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozcrash 04:10:26 INFO - Running setup.py install for mozdebug 04:10:26 INFO - Running setup.py install for mozdevice 04:10:26 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozfile 04:10:26 INFO - Running setup.py install for mozhttpd 04:10:26 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozinfo 04:10:26 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozInstall 04:10:26 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 04:10:26 INFO - Running setup.py install for mozleak 04:10:27 INFO - Running setup.py install for mozlog 04:10:27 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Running setup.py install for moznetwork 04:10:27 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Running setup.py install for mozprocess 04:10:27 INFO - Running setup.py install for mozprofile 04:10:27 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Running setup.py install for mozrunner 04:10:27 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Running setup.py install for mozscreenshot 04:10:27 INFO - Running setup.py install for moztest 04:10:27 INFO - Running setup.py install for mozversion 04:10:27 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 04:10:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:10:27 INFO - Cleaning up... 04:10:27 INFO - Return code: 0 04:10:27 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:10:27 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')]} 04:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 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')]} 04:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:27 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 04:10:27 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 04:10:27 INFO - Using env: {'DISPLAY': ':0', 04:10:27 INFO - 'HOME': '/home/cltbld', 04:10:27 INFO - 'LANG': 'en_US.UTF-8', 04:10:27 INFO - 'LANGUAGE': 'en_US:en', 04:10:27 INFO - 'LOGNAME': 'cltbld', 04:10:27 INFO - 'MAIL': '/var/mail/cltbld', 04:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:27 INFO - 'MOZ_NO_REMOTE': '1', 04:10:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:27 INFO - 'NO_EM_RESTART': '1', 04:10:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:27 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:27 INFO - 'SHELL': '/bin/bash', 04:10:27 INFO - 'SHLVL': '1', 04:10:27 INFO - 'TERM': 'linux', 04:10:27 INFO - 'TMOUT': '86400', 04:10:27 INFO - 'USER': 'cltbld', 04:10:27 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:27 INFO - '_': '/tools/buildbot/bin/python'} 04:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:28 INFO - Running setup.py (path:/tmp/pip-WNnRfg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:28 INFO - Running setup.py (path:/tmp/pip-SnKtBp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:28 INFO - Running setup.py (path:/tmp/pip-u95jac-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:28 INFO - Running setup.py (path:/tmp/pip-Z3oDEg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:28 INFO - Running setup.py (path:/tmp/pip-x0l2CH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:28 INFO - Running setup.py (path:/tmp/pip-FgcGKo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:28 INFO - Running setup.py (path:/tmp/pip-5BpGCa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:28 INFO - Running setup.py (path:/tmp/pip-6w07ix-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:28 INFO - Running setup.py (path:/tmp/pip-wWPRRC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:28 INFO - Running setup.py (path:/tmp/pip-bZxuSX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:28 INFO - Running setup.py (path:/tmp/pip-cH6hZP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:10:28 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)) 04:10:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:28 INFO - Running setup.py (path:/tmp/pip-c2MaRp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:10:29 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)) 04:10:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:29 INFO - Running setup.py (path:/tmp/pip-b9sBtq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:10:29 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)) 04:10:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:29 INFO - Running setup.py (path:/tmp/pip-CGxA7P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:10:29 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)) 04:10:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:29 INFO - Running setup.py (path:/tmp/pip-gSjPRw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:10:29 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)) 04:10:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:29 INFO - Running setup.py (path:/tmp/pip-HUaFBl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:10:29 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)) 04:10:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:29 INFO - Running setup.py (path:/tmp/pip-OVHjD2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:10:29 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)) 04:10:29 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)) 04:10:29 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)) 04:10:29 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)) 04:10:29 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)) 04:10:29 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)) 04:10:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 04:10:29 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 04:10:29 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 04:10:29 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 04:10:29 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 04:10:29 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 04:10:29 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 04:10:31 INFO - Downloading blessings-1.5.1.tar.gz 04:10:31 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 04:10:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 04:10:31 INFO - Installing collected packages: blessings 04:10:31 INFO - Running setup.py install for blessings 04:10:31 INFO - Successfully installed blessings 04:10:31 INFO - Cleaning up... 04:10:31 INFO - Return code: 0 04:10:31 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 04:10:31 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:10:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:10:31 INFO - Reading from file tmpfile_stdout 04:10:31 INFO - Current package versions: 04:10:31 INFO - argparse == 1.2.1 04:10:31 INFO - blessings == 1.5.1 04:10:31 INFO - blobuploader == 1.2.4 04:10:31 INFO - docopt == 0.6.1 04:10:31 INFO - manifestparser == 1.1 04:10:31 INFO - mozInstall == 1.12 04:10:31 INFO - mozcrash == 0.16 04:10:31 INFO - mozdebug == 0.1 04:10:31 INFO - mozdevice == 0.46 04:10:31 INFO - mozfile == 1.2 04:10:31 INFO - mozhttpd == 0.7 04:10:31 INFO - mozinfo == 0.8 04:10:31 INFO - mozleak == 0.1 04:10:31 INFO - mozlog == 3.0 04:10:31 INFO - moznetwork == 0.27 04:10:31 INFO - mozprocess == 0.22 04:10:31 INFO - mozprofile == 0.27 04:10:31 INFO - mozrunner == 6.11 04:10:31 INFO - mozscreenshot == 0.1 04:10:31 INFO - mozsystemmonitor == 0.0 04:10:31 INFO - moztest == 0.7 04:10:31 INFO - mozversion == 1.4 04:10:31 INFO - psutil == 3.1.1 04:10:31 INFO - requests == 1.2.3 04:10:31 INFO - wsgiref == 0.1.2 04:10:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:10: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')]} 04:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10: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')]} 04:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:31 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:31 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 04:10:31 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 04:10:31 INFO - Using env: {'DISPLAY': ':0', 04:10:31 INFO - 'HOME': '/home/cltbld', 04:10:31 INFO - 'LANG': 'en_US.UTF-8', 04:10:31 INFO - 'LANGUAGE': 'en_US:en', 04:10:31 INFO - 'LOGNAME': 'cltbld', 04:10:31 INFO - 'MAIL': '/var/mail/cltbld', 04:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:31 INFO - 'MOZ_NO_REMOTE': '1', 04:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:31 INFO - 'NO_EM_RESTART': '1', 04:10:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:31 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:31 INFO - 'SHELL': '/bin/bash', 04:10:31 INFO - 'SHLVL': '1', 04:10:31 INFO - 'TERM': 'linux', 04:10:31 INFO - 'TMOUT': '86400', 04:10:31 INFO - 'USER': 'cltbld', 04:10:31 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:31 INFO - '_': '/tools/buildbot/bin/python'} 04:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 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)) 04:10:31 INFO - Cleaning up... 04:10:31 INFO - Return code: 0 04:10:31 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 04:10: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')]} 04:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10: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')]} 04:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:31 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:31 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 04:10:31 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 04:10:31 INFO - Using env: {'DISPLAY': ':0', 04:10:31 INFO - 'HOME': '/home/cltbld', 04:10:31 INFO - 'LANG': 'en_US.UTF-8', 04:10:31 INFO - 'LANGUAGE': 'en_US:en', 04:10:31 INFO - 'LOGNAME': 'cltbld', 04:10:31 INFO - 'MAIL': '/var/mail/cltbld', 04:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:31 INFO - 'MOZ_NO_REMOTE': '1', 04:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:31 INFO - 'NO_EM_RESTART': '1', 04:10:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:31 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:31 INFO - 'SHELL': '/bin/bash', 04:10:31 INFO - 'SHLVL': '1', 04:10:31 INFO - 'TERM': 'linux', 04:10:31 INFO - 'TMOUT': '86400', 04:10:31 INFO - 'USER': 'cltbld', 04:10:31 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:31 INFO - '_': '/tools/buildbot/bin/python'} 04:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:31 INFO - Downloading/unpacking jsonschema 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:10:33 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 04:10:33 INFO - Downloading/unpacking functools32 (from jsonschema) 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 INFO - Downloading functools32-3.2.3-2.tar.gz 04:10:33 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:10:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 04:10:33 INFO - warning: no files found matching '*.txt' 04:10:33 INFO - no previously-included directories found matching 'build' 04:10:33 INFO - no previously-included directories found matching 'dist' 04:10:33 INFO - no previously-included directories found matching '.git*' 04:10:33 INFO - Installing collected packages: jsonschema, functools32 04:10:34 INFO - Running setup.py install for functools32 04:10:34 INFO - warning: no files found matching '*.txt' 04:10:34 INFO - no previously-included directories found matching 'build' 04:10:34 INFO - no previously-included directories found matching 'dist' 04:10:34 INFO - no previously-included directories found matching '.git*' 04:10:34 INFO - Successfully installed jsonschema functools32 04:10:34 INFO - Cleaning up... 04:10:34 INFO - Return code: 0 04:10:34 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 04:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:34 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 0x7f0b9d05bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', '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 04:10:34 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 04:10:34 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 04:10:34 INFO - Using env: {'DISPLAY': ':0', 04:10:34 INFO - 'HOME': '/home/cltbld', 04:10:34 INFO - 'LANG': 'en_US.UTF-8', 04:10:34 INFO - 'LANGUAGE': 'en_US:en', 04:10:34 INFO - 'LOGNAME': 'cltbld', 04:10:34 INFO - 'MAIL': '/var/mail/cltbld', 04:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:34 INFO - 'MOZ_NO_REMOTE': '1', 04:10:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:34 INFO - 'NO_EM_RESTART': '1', 04:10:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:34 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:34 INFO - 'SHELL': '/bin/bash', 04:10:34 INFO - 'SHLVL': '1', 04:10:34 INFO - 'TERM': 'linux', 04:10:34 INFO - 'TMOUT': '86400', 04:10:34 INFO - 'USER': 'cltbld', 04:10:34 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:34 INFO - '_': '/tools/buildbot/bin/python'} 04:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:34 INFO - Downloading/unpacking flake8 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:10:36 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 04:10:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:10:36 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 04:10:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:36 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 04:10:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:10:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:36 INFO - no previously-included directories found matching 'docs/_build' 04:10:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:10:36 INFO - Running setup.py install for pep8 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:36 INFO - no previously-included directories found matching 'docs/_build' 04:10:36 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 04:10:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:10:36 INFO - Cleaning up... 04:10:36 INFO - Return code: 0 04:10:36 INFO - Running post-action listener: _resource_record_post_action 04:10:36 INFO - Running post-action listener: _start_resource_monitoring 04:10:36 INFO - Starting resource monitoring. 04:10:36 INFO - ##### 04:10:36 INFO - ##### Running install step. 04:10:36 INFO - ##### 04:10:36 INFO - Running pre-action listener: _resource_record_pre_action 04:10:36 INFO - Running main action method: install 04:10:36 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:10:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:10:37 INFO - Reading from file tmpfile_stdout 04:10:37 INFO - Detecting whether we're running mozinstall >=1.0... 04:10:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 04:10:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 04:10:37 INFO - Reading from file tmpfile_stdout 04:10:37 INFO - Output received: 04:10:37 INFO - Usage: mozinstall [options] installer 04:10:37 INFO - Options: 04:10:37 INFO - -h, --help show this help message and exit 04:10:37 INFO - -d DEST, --destination=DEST 04:10:37 INFO - Directory to install application into. [default: 04:10:37 INFO - "/builds/slave/test-pgo"] 04:10:37 INFO - --app=APP Application being installed. [default: firefox] 04:10:37 INFO - mkdir: /builds/slave/test-pgo/build/application 04:10:37 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'] 04:10:37 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 04:10:51 INFO - Reading from file tmpfile_stdout 04:10:51 INFO - Output received: 04:10:51 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 04:10:51 INFO - Running post-action listener: _resource_record_post_action 04:10:51 INFO - ##### 04:10:51 INFO - ##### Running run-tests step. 04:10:51 INFO - ##### 04:10:51 INFO - Running pre-action listener: _resource_record_pre_action 04:10:51 INFO - Running main action method: run_tests 04:10:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 04:10:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 04:10:51 INFO - Python 2.7.3 04:10:51 INFO - Return code: 0 04:10:51 INFO - grabbing minidump binary from tooltool 04:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:51 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 0xe0d350>, '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 0xe0e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0e6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 04:10:51 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 04:10:51 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 04:10:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:10:51 INFO - Return code: 0 04:10:51 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 04:10:51 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 04:10:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 04:10:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 04:10:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 04:10:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 04:10:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 04:10:52 INFO - Return code: 0 04:10:52 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/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 04:10:52 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/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost 04:10:52 INFO - Using env: {'DISPLAY': ':0', 04:10:52 INFO - 'HOME': '/home/cltbld', 04:10:52 INFO - 'LANG': 'en_US.UTF-8', 04:10:52 INFO - 'LANGUAGE': 'en_US:en', 04:10:52 INFO - 'LOGNAME': 'cltbld', 04:10:52 INFO - 'MAIL': '/var/mail/cltbld', 04:10:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:10:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 04:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:52 INFO - 'MOZ_NO_REMOTE': '1', 04:10:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:10:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:52 INFO - 'NO_EM_RESTART': '1', 04:10:52 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:10:52 INFO - 'PWD': '/builds/slave/test-pgo', 04:10:52 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 04:10:52 INFO - 'SHELL': '/bin/bash', 04:10:52 INFO - 'SHLVL': '1', 04:10:52 INFO - 'TERM': 'linux', 04:10:52 INFO - 'TMOUT': '86400', 04:10:52 INFO - 'USER': 'cltbld', 04:10:52 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427', 04:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:52 INFO - '_': '/tools/buildbot/bin/python'} 04:10:52 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/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] with output_timeout 3600 04:10:52 INFO - mozversion INFO | application_buildid: 20151214205110 04:10:52 INFO - mozversion INFO | application_changeset: d1a4b331d80bc4daea15a7df4057205483b1f276 04:10:52 INFO - mozversion INFO | application_display_name: Nightly 04:10:52 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:10:52 INFO - mozversion INFO | application_name: Firefox 04:10:52 INFO - mozversion INFO | application_remotingname: firefox 04:10:52 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:10:52 INFO - mozversion INFO | application_vendor: Mozilla 04:10:52 INFO - mozversion INFO | application_version: 44.0 04:10:52 INFO - mozversion INFO | platform_buildid: 20151214205110 04:10:52 INFO - mozversion INFO | platform_changeset: d1a4b331d80bc4daea15a7df4057205483b1f276 04:10:52 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:10:52 INFO - mozversion INFO | platform_version: 44.0 04:10:52 INFO - 2015-12-16 04:10:52,664 DEBUG : using testdate: 1450267852 04:10:52 INFO - 2015-12-16 04:10:52,664 DEBUG : actual date: 1450267852 04:10:52 INFO - 2015-12-16 04:10:52,800 INFO : starting webserver on 'localhost:60858' 04:10:52 INFO - 2015-12-16 04:10:52,802 INFO : Starting test suite talos-linux64-ix-035 04:10:52 INFO - 2015-12-16 04:10:52,802 INFO : Starting test dromaeo_css 04:10:52 INFO - 2015-12-16 04:10:52,802 DEBUG : operating with platform_type : linux_ 04:10:52 INFO - 2015-12-16 04:10:52,803 INFO : Initialising browser for dromaeo_css test... 04:10:52 INFO - 2015-12-16 04:10:52,819 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfQJ861/profile http://localhost:60858/getInfo.html 04:10:56 INFO - 2015-12-16 04:10:56,196 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:10:56 INFO - 2015-12-16 04:10:56,196 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:10:56 INFO - 2015-12-16 04:10:56,196 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:10:56 INFO - 2015-12-16 04:10:56,196 DEBUG : BROWSER_OUTPUT: __metrics 04:10:56 INFO - 2015-12-16 04:10:56,325 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] 04:10:57 INFO - 2015-12-16 04:10:57,289 INFO : Browser initialized. 04:10:57 INFO - 2015-12-16 04:10:57,290 INFO : Running cycle 1/1 for dromaeo_css test... 04:10:57 INFO - 2015-12-16 04:10:57,290 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfQJ861/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:11:08 INFO - 2015-12-16 04:11:08,899 DEBUG : BROWSER_OUTPUT: RSS: Main: 129712128 04:11:08 INFO - 2015-12-16 04:11:08,899 DEBUG : BROWSER_OUTPUT: 04:11:59 INFO - 2015-12-16 04:11:59,738 DEBUG : BROWSER_OUTPUT: 1450267919737 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] 04:14:07 INFO - 2015-12-16 04:14:07,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60858/tests/dromaeo/cssquery-ext.html) 04:14:08 INFO - 2015-12-16 04:14:08,998 DEBUG : BROWSER_OUTPUT: RSS: Main: 233807872 04:14:08 INFO - 2015-12-16 04:14:08,998 DEBUG : BROWSER_OUTPUT: 04:17:11 INFO - 2015-12-16 04:17:11,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/dromaeo/cssquery-ext.html (next: http://localhost:60858/tests/dromaeo/cssquery-jquery.html) 04:17:11 INFO - 2015-12-16 04:17:11,616 DEBUG : BROWSER_OUTPUT: RSS: Main: 205549568 04:17:11 INFO - 2015-12-16 04:17:11,617 DEBUG : BROWSER_OUTPUT: 04:20:13 INFO - 2015-12-16 04:20:13,277 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60858/tests/dromaeo/cssquery-mootools.html) 04:20:13 INFO - 2015-12-16 04:20:13,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 202485760 04:20:13 INFO - 2015-12-16 04:20:13,838 DEBUG : BROWSER_OUTPUT: 04:23:15 INFO - 2015-12-16 04:23:15,554 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60858/tests/dromaeo/cssquery-prototype.html) 04:23:16 INFO - 2015-12-16 04:23:16,116 DEBUG : BROWSER_OUTPUT: RSS: Main: 193052672 04:23:16 INFO - 2015-12-16 04:23:16,116 DEBUG : BROWSER_OUTPUT: 04:26:18 INFO - 2015-12-16 04:26:18,450 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60858/tests/dromaeo/cssquery-yui.html) 04:26:19 INFO - 2015-12-16 04:26:19,011 DEBUG : BROWSER_OUTPUT: RSS: Main: 194805760 04:26:19 INFO - 2015-12-16 04:26:19,011 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,100 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/dromaeo/cssquery-yui.html (next: http://localhost:60858/tests/dromaeo/cssquery-dojo.html) 04:29:21 INFO - 2015-12-16 04:29:21,350 DEBUG : BROWSER_OUTPUT: RSS: Main: 231260160 04:29:21 INFO - 2015-12-16 04:29:21,350 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,351 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:29:21 INFO - 2015-12-16 04:29:21,352 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:29:21 INFO - 2015-12-16 04:29:21,352 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:29:21 INFO - 2015-12-16 04:29:21,352 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:29:21 INFO - 2015-12-16 04:29:21,353 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3380.03668954348;3431.6225215226336;3443.6384179661136;3449.5170676105367;3485.3900567400697;5719.456651618095;5779.190913272135;5788.507976821965;5791.305043394788;5798.3042034955815;5762.740676669549;5806;5819;5839.532837373011;5858.5606079543995;5048.974755126219;5224.529792318691;5271.789128434877;5342.679439233659;5361.276227709268;5199.896002079956;5207.817726379578;5224.451432599561;5232.372115346145;5240.135377662681;5241.2400201970695;5313.0967735485065;5372.892542149155;5390.245365648812;5404.243405923174;5297.205419187114;5311.840644780663;5316.813911513098;5319.521243088102;5322;5047.293378926953;5058.418281897592;5177.119889618773;5208.140656791663;5234.398044224925;5548.639338442988;5570.6657600544095;5578.3584887738025;5582.748776305076;5585.134304182858;6213.440790328847;6216.160818289518;6216.160818289563;6216.347283535209;6224.688765561749;6036.849078773066;6143.232095988002;6163.753449862;6256.718447669866;6267.1539342189135;5859.033259512175;5875.441833025813;5876.588638795286;5877.235959325303;5880.235569375954;6322.272938612026;6347.873042539216;6372.745090196433;6381.202349706286;6385.616862988236;5855.736491857877;5949.10763385487;6085.208922840003;6125.947807484543;6159.722812473449;6275.6548389839;6276.592021518587;6276.6547839868335;6277.340879207709;6278;6081.331053584191;6241.18864547615;6248.094026366151;6249.343818899041;6254.9687251563455;6134.215925598752;6191.164192834;6344.397282258178;6354.269259035178;6357.650329231937;4750.904981900412;4754.643401744883;4755.167845627001;4782.736949467743;4789.090072886141;4146.813393397304;4222.0711443483;4293.162833247549;4300.978495107567;4304.978475107604;5877.265341832271;5999.340072592011;6015.0977353396465;6015.849603759941;6035.064564992345;6335.334789847092;6336.493080553545;6361.077643741632;6369.458596019297;6370.617762934239;5696.857578560569;5739.339975902742;5797.768089276466;5942.851428714231;5955.880882382328;5404.91892621611;5405.351357837006;5405.351357837085;5412.675239485643;5467.72661366938;6423.935760642427;6446.065320528497;6447.355264473609;6447.516436267299;6455.9677201613695;6022.307434644897;6116.602420842631;6129.601575897553;6240.719167637377;6299.086632438362;6381.361863813581;6583.96708016476;6589.27517973013;6591.307912669293;6593.901091483727;6276.466500347522;6294.087357333069;6297.874042519033;6298.968505157445;6312.905306420499;6087.695615219132;6121.878680213292;6157.9692101540995;6233.314335423009;6233.812985610439;6106.786262480792;6140.17107690456;6141.7543298267565;6142;6146.907796383147;5837.241158649348;6097.4817140543555;6101.389861013863;6104.298005729398;6107;5974.641521508724;5984.102384642164;6089.238845144357;6099.4815440688035;6105.755769769337;6103.908441373294;6110.816675499742;6145.201123853871;6155.876882462236;6156.107364432043;5810.099434587646;5940.495057919952;5941.1385349125;5947.375525569926;5971.641701497924 04:29:21 INFO - 2015-12-16 04:29:21,357 DEBUG : BROWSER_OUTPUT: |1;ext.html;601.4286427893502;649.6913965866213;654.4666097130842;655.4363247607054;657.9144711187545;36177.45733813992;44453.77773111134;44870.55129448704;45074.32388514175;45248.54751452484;48827;49478.75260623692;49541.50458495414;49572.75213623936;49579.50420495794;8311.83376332473;8436.789080272982;8922.06318336575;9083.591238394256;10441.321314114559;10712.035916767489;10875.564977400894;10896.128309735202;10898.237123401326;10903;10897.727556811104;10987.450627468634;10991.890081099167;10995.120390368751;11012.889871101266;10868.347899126038;10938.617148399768;10950.780984380348;10964.342139471577;10965.671029869116;10696.78606427867;10794.2444028918;10814.891851081467;10824.675259742142;10825.83761243582;45640;46041.30938035932;46084.53915460836;46106;46168;3641.417373220272;3774.8112594370446;3846.692264618852;4075.7758323292205;4455.198064348416;34520;34826.47760283598;34977.30045399103;35030.82484587586;35825.46261806075;3894.6884249260274;3918.8628398005935;3928.5678575356974;3981.163955569305;4081.5306239782212;302355;303335;305120;451920;455375;75154.62422687851;75876.62061689656;76137.61931190308;76177.23822761812;76245.61877190579;70533.64733176403;70643.64678176577;70734;70786.64606676933;70872;18308.542286442946;18578.535536611693;18613.34853280156;18656.440306790817;18660.16029278686;14194.148351098967;14373.712525749635;14418.495352662607;14452.710945781026;14471.855281447051;22461.989210485568;22644.773552264265;22705.772942270367;22740.317790466313;22779.430514236945;8135.3898457615915;8384.538850363166;8391.790205244795;8398.958005210057;8468.788280293042;1014.624588902111;1070.3952266969138;1115.7210697325668;1165.5454372794618;1171.9531218751324;30319.848400757855;30478;30959;31618.841905790323;31668;1119.3731510354228;1124.2630136439059;1131.7227279316621;1200.2918278215895;1212.9878701213051;13710;15057.472988445352;15068.246587670792;15574.532764017098;15621.765673514907;369595;375597;377034;377265;377660;60198.699006504685;60541;60610.39389606047;60632.393676062675;60682.39317606856;1329.2157626999924;1428.8071110400172;1432.1979691372865;1537.5771662792822;1568.8431156884449;30429.543556846224;30513.542296865122;30542;31077.067687969397;31231.219219519695;50636.24045639245;50889;50985.23522147097;50992.49007509877;50992.74503627458;280145;280205;281040;283328;284889;36230;36270.637293627784;36617.45073823842;36645.816770915975;36999;43123.784381077894;43834.78082609694;44291.33562996622;44628.77685611551;44943.32585011293;37079.82094932715;37678.434823478216;37714.43428348522;40919.1816163681;41382.79308603438;40313.59686403099;40410.79794601008;40544.1891162181;41471;41686.16627667369;39739.60260397359;40057;40123.39814902837;40345.59654403418;40406.595934040284;35249;35736.463953040206;36065.27869442544;36205.27589448249;36275.63724362723;27092.593611096243;27815;27861.303467413476;27905.720942791126;27945.441091177658 04:29:21 INFO - 2015-12-16 04:29:21,357 DEBUG : BROWSER_OUTPUT: |2;jquery.html;396.0692372923628;397.8786470126612;399.41286309125593;400.47737702298497;402.23173738160114;16209.270582823772;16692.58268543287;19136.521586960334;19291.421257362268;19657;17943.730844037316;18035.90982045088;18180.63638727228;18206.908965455154;18684.346047888328;5752.165935939286;5844.853878653046;5884.323302820188;5900.409959004087;5901.203337549418;4408.86773396797;5869.383714709958;6002.789902353418;6418.743250269984;6437.066625339323;6270.122182894398;6350.460210882081;6353.142325786029;6390.808275751735;6393.7762178323765;6275.058741188813;6304.243490781089;6316.684165791692;6340.936590634104;6347.777827776005;5477.123660214346;5529.834104976857;5612.102063669793;5621.269234999424;5632.802851900205;13951.511697090553;14277.643058923506;14332.498362557364;23182.53634927309;23360.18239361614;2231.319447568491;2291.014863608648;2314.953700925989;2575.291794756457;2781.680106787725;11063.889361106367;11158.776824463464;11235.943820280847;11272.210945233837;11306.095512358992;1593.8804589655822;1711.212842092639;1775.9911200444044;1813.4831573001632;1898.5918027623989;65590.3440965589;65687.67156164236;65979.67010164919;66483.3351666482;66804.99999999952;7654.081510218698;7790.688372465038;7905.56519391439;7929.1277959424415;7982.920170798334;9466.857997130048;9500.23998080152;9511.286653500876;9528.047195280555;9532.666356677482;6296.401841825019;6387.744490220339;6408.871822563523;6433.935660643334;6441.903371449432;6414.679266036679;6442.130312407859;6446.419822216023;6453.193350831146;6467.51493637979;3681.355762741563;3728.105254738843;3731.1045349116016;3756.8166027701186;3757.1734218471893;1262.949482020709;1286.3053950866615;1299.7205600795874;1304.4847285322435;1338.8460327062319;501.98745031374506;529.8807768252112;533.3679589686234;534.5856960855368;534.8448949804591;11549.36478493691;13282.867171328164;13753.106048106842;13768.311584420739;13846.30768461573;537.5430883748783;541.4531323363432;544.7984245829068;545.0135255188122;572.8510587247346;7226.674799633924;7267.745628902963;7518.097828260644;7587.848243035109;7644.12092609357;624834;625912;645649;678815;680710;82932.58533707293;85363.14636853676;86210;88099;90234;572.6478215897276;583.5185971573469;584.3046774338609;584.7485581200125;586.642148289543;12275.386230688251;12547;12652.683682908;12697.174683645533;12811.87188128144;39025.21949560936;39063.609363906;39330.98818661264;39879.20241595094;40493.59506404898;48437.27344089771;52735;53171.46828531665;53360.73319633377;54294.18558721701;7615.657295421608;7791.766247012599;7839.25527074927;7886.171951944963;7990.200979901963;11177;13990.230537320138;14184.787228191792;14238.35927383291;14546.636334091732;12322.137450378388;12474.376281185723;12558.183718058297;12624.3687815607;12725.61823145307;11798.469068891749;12137.21108127969;12267.079969002181;12425.813612795635;12682;11865;12409.255444672988;12567.87432125667;12736.745265094462;12789.61631151067;7562.470627056054;7712.691492340244;7714.6914123434435;7717.498362606412;7761.417893658111;12285.57000504978;12382.504699811907;12448.688782780502;12534.623961281177;12572.874271257171 04:29:21 INFO - 2015-12-16 04:29:21,358 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1585.7224985627513;1586.4050980882162;1820.5903671673866;1840.300685739419;1865.608222273322;7569.697212111509;7742.961285193566;8170.754877353674;8536.573171341439;8563.61463734132;7892.526448413085;8028.799280018003;8104.432689711723;8109.148539403366;8111.553864537448;2450.4363996280867;2486.3038349262238;2486.316263027664;2487.141936032066;2487.5398051360467;2187.824974002076;2233.810126139272;2431.9392015199674;2467.864267465289;2519.483505881299;2413.6379543068506;2425.2650917586893;2437.4393889405446;2441.816863735227;2453.7178224504232;2304.873231972241;2324.465372964211;2325.9534809303805;2327.8254130940245;2329.4176455886027;2422.067504010951;2441.389652586853;2443.486867757773;2444.4133407982135;2446.388402899275;8403.789905252357;8473;8475.74572762818;8479.406441549094;8488.915110848875;5188.610854185951;5224.477552244745;5245.6852588844795;5261.710605916673;5266.947330526722;3320.4023275810346;3330.400527904976;3431.0564594736397;3453.8100404477746;3461.978716278679;1082.9458527073614;1099.8845121262232;1124.5670416889557;1149.0807354116673;1186.7507823357018;11626.418679066097;11631.127665425127;11636.12729045325;11640.708982275426;11641;4712.269598212282;4738.1708201064675;4744.833930812405;4780;4793.832215872428;9791.46146961924;9831.80336393268;9833.360831545926;9837.163841073445;9871.259655525864;5228.55557277628;5233.895322093613;5237.685738855681;5238.214267859776;5238.476152384807;4724.669273150916;4726.598239149711;4732.100900828831;4733.573978341966;4734.053189362141;3719.814009299524;4121.690873184523;4150.481189851269;4153.522344930311;4186.7278626889765;1260.7352455984346;1281.7949128139453;1368.3021658954005;1382.2535830651536;1382.3641125082574;1186.9287842729464;1219.3354621731178;1221.5175005872632;1512.9319180636899;1538.8230353509266;7970.960145199353;8104.824800403909;8306.8338633227;8340.749777506684;8342.124076971952;1137.4938152522213;1181.5982565927625;1220.2190598017276;1221.6988906814215;1246.3394400967502;6557.147570815859;6585.440237579764;6611.23970743361;6629.105070815378;6637.933620663828;9939.055789699967;9974.501274936225;10042.347247428894;10063.5471403787;10073.143782778547;9729.124378805942;9918;9946.452945087945;9959.900400996043;9984.15134713543;7958.124606293303;7998.4001199910235;8025.2777250047775;8029;8036.196380362034;8044.758657240292;8114.675412983414;8143.307818835466;8150.347972162094;8185.263326300662;9059.95470022672;9087.409318394282;9141.451512909247;9159.084091590788;9223.44659320443;9161.404508706913;9340.906590934004;9347.859782103138;9442.10300021497;9484.478353690612;7635.350995165473;7643.312101910855;7711.421643376882;7738.342240909586;7745.65144568507;7264.491485596169;7382.815429614303;8250.752477425687;8369.037560680355;8374.078851326227;7608.2011388805595;7788.84422311562;7798.766037018899;7801.3368863644955;7943.682252709827;7706.691732330644;7734.265244801735;7750.186230445722;7825.608719564113;7845.137034926268;7854.410919181199;7884.448088633741;7962.482438641469;7967.04395472547;7975.282224599814;7036.366726994595;7093.113360829896;7093.467989900882;7097.751578694927;7107.964460177666;6994.125734283215;7073.292670732885;7130.5365151265005;7187.640617968976;7208.27917208275 04:29:21 INFO - 2015-12-16 04:29:21,359 DEBUG : BROWSER_OUTPUT: |4;prototype.html;373.3690063792191;379.7360834220218;381.27177091754754;384.1721091048789;385.458430904579;5948.405159484049;5990.970045149774;6010.549208809346;6021.488173505258;6073.5444841636945;5917.5561832862495;5921.496672782819;5953.642781433117;5955.34491205967;5960.7317670704815;4552.635789136878;4652.604528615072;4672.0889426561835;4673.556012178855;4686.414198225222;4679.087577922306;4683.157031734287;4685.508021657711;4696.882577935545;4699.036697477009;4491.236489796726;4541.886452838672;4562.726236425809;4647.256438969748;4651.4418269807675;4586.449626044862;4609.1473077480605;4613.469451013143;4616.49218585957;4621.560951709615;4050.311447054008;4056.5334986476637;4061.411095391152;4062.7562346259024;4065.0853557949226;5512.724363781835;5583.58123140762;5593.720313984284;5675.404082571312;5700.344460387067;5354.036273470773;5355.866103347447;5360.16917377807;5360.758765855507;5363.704996225206;6225.844353891143;6277.560570760049;6313.71588278524;6323.336049714806;6333.9683301583655;4487.483939346984;4488.8877778055485;4495.168393847149;4575.862724118282;4582.037772067836;6469.74121035158;6489.967550162266;6521.18485189356;6526.477881769447;6539.280679125292;4655.627549796008;4657.673962822638;4675.929861052087;4676.6492513060975;4693.389859318267;4718.598919091915;4757.500462451462;4757.548032936935;4760.238361862154;4762.404699412574;5319.7340132993195;5454.072807622713;5474.589405794582;5477.890442191214;5480.725963701799;6460.353964603502;6467.741290348334;6469.417752402306;6472.3204063573585;6475.417212450902;1706.7781188445422;1728.5073753980055;1728.8184740602055;1729;1729.481155653299;1155.1451925575182;1160.4081918221768;1168.5617893290016;1184.8933595976403;1185.8873407026492;6205.782797602063;6245.250569931637;6250.499960003189;6251.874962500725;6253.312135665074;6496.610203387811;6542.084108224757;6552;6553.246376666649;6607.372299631527;6120;6195.411435913671;6202.379762023852;6204.224471941007;6245.531585131133;3632.636736326399;3645.0705070206927;3650.543682039745;3652.324320000755;3652.945205821915;6532.346765323429;6616.768413105518;6683.064370988066;6797.932020679828;6798.66006699663;6744.393004629607;6748.392644661906;6761.932380676229;6765.391114799593;6774.932250677529;6740.932590674129;6748.696308666122;6750.831229219309;6753.797386078426;6755.3582409671;6467.159269294962;6581.80254592363;6586.308437614173;6589.341065893373;6591.307912669293;6535.803925882231;6610.603363797995;6615.239247486601;6619.602823830585;6620.3710647488415;6804.11546498952;6852.280510546321;6853.17761868579;6853.417459515797;6865.313468653095;4591.2194926863185;4679.766011699469;4680.134175177603;4680.485146633936;4681.297805329092;6665.900011499928;6667.566608170453;6670.466362691071;6673.9666301668185;6676.56602320867;6466.902996454963;6481.708323125565;6483;6487.708053137714;6490.513211509061;6302.1492098566105;6463.676816159079;6468.5472016958365;6472.320406357265;6483.189601299838;6377.043443483514;6378.52161087911;6379.840503987251;6380.425761681471;6381.234251889618;6499.057636642755;6510.153680021567;6510.47916166715;6512.706928188338;6516.609003459807;6130.938690613037;6212.471939884979;6223.253209614875;6224.470919971853;6310.5267104968225 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: |5;yui.html;910.2990697163184;931.2922179143849;960.2941837749253;967.9516024198788;972.1299437003877;14319.498817541387;14846.703065938687;14863;14867.479638212664;14874.10755354678;15489.69020619587;15805.130717810545;15821.762673559877;15824.446144384949;15873.682526349467;765.5789315876274;772.0195351903079;919.5769945824912;927.1053433436732;945.465811816325;927.6567669962124;936.0826390137669;942.29328003997;943.9622415103388;944.1596978688972;981.5730157381527;988.4958671077766;990.0495524296684;990.4849478271294;991.028791784052;977.5307852230933;978.0708327089294;979.1970584121024;979.427035184418;979.5298256836687;709.3722055980443;716.1012928774406;716.2407847681475;716.4912911832605;716.7491378017704;14561.635959101108;14651.560453186315;14703.55889323322;14709.779353309708;14722.926385368111;11302.830457543143;11368.147388945863;11390.316581005167;11510.482028308747;11752.353620550866;10420.739481512946;10526.684199473952;10935.398553079578;11120.054795342325;11129.054030407426;3598.6941110005682;3605.2068544920076;3609.043603445089;3614.4397618369194;3616.801075940822;47342.526574734155;47363;47721.52278477205;47979;48028.75985620049;8322.084570697218;8329.833403331899;8395.62219700115;8405.117462666454;8408.537530435884;12681.809772853414;12988.155769874928;12995.025373097056;12995.090343675853;13035.02237332204;7368.742094026683;7456.1798202197715;7529.623518824037;7562.621868906643;7669.501482403626;4810.350602668665;4832.202686556749;4845.079434907357;4855.830045948375;4901.656884018084;2987.1635941936293;2997.2656699108857;3005.0834495478653;3006.6091408116804;3007.8345690987207;469.4343316303914;473.36096270035176;481.72300926967137;491.13805271748566;491.22386629126606;701.1586096684;752.1763668782675;758.6206896551724;768.4966347042696;787.2245837849714;9406.670766523139;9777.951110244403;9860.260480463994;9893.389766216147;10025.395936650248;601.3955974245855;651.5536857252703;694.2398074108843;766.3141488367879;801.054755388647;4826.541478559601;4888.120138375056;4944.233643785219;5031.698098114051;5071.391433027987;60595.09107363393;60973;61554.38445615487;67640.6617966907;67940.3205967944;59208.407915921154;59925;60127.69936150291;60800.391996079474;60959.39040609626;52384.214236785716;52413;53158.73420632872;53365;53934.730326348115;9839.827162333251;9841.655542055994;9854.650873190812;9910.683957080322;10323.793524129325;40032.3995140079;40127.79936100301;41135.58864411317;41136.79431602823;41319.96700082522;52316;52740;53085.20372194343;53721;54166.729166353914;12573.308468034345;12608.558700445441;12651.240925544496;12776.680582985127;13142;13271.734565308832;13552.390142443417;13872.514461993782;14083.577492675236;14219.360128794022;13024.153430027018;13110.213387196798;13247.602571922858;13269.469221231042;13432;12552.937235313764;12671.239725616493;12839.550615728778;12866.67833304175;12894.161879477804;12035.578754743541;12045.578404755792;12920.676983075498;13023.47906083746;13279.203247805162;10840.024397804236;11005;11017.889821102008;11154.553817847196;11508.654740357802;10817.45912704346;10959.452027398758;11240.830869985179;11248.381339026431;11253.324800511995 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,783 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: __startTimestamp1450268961350__endTimestamp 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5782.58 Median:6015.10 stddev:661.80 (11.0%) stddev-sans-first:636.43 04:29:21 INFO - 2015-12-16 04:29:21,361 DEBUG : BROWSER_OUTPUT: Values: 3380.0 3431.6 3443.6 3449.5 3485.4 5719.5 5779.2 5788.5 5791.3 5798.3 5762.7 5806.0 5819.0 5839.5 5858.6 5049.0 5224.5 5271.8 5342.7 5361.3 5199.9 5207.8 5224.5 5232.4 5240.1 5241.2 5313.1 5372.9 5390.2 5404.2 5297.2 5311.8 5316.8 5319.5 5322.0 5047.3 5058.4 5177.1 5208.1 5234.4 5548.6 5570.7 5578.4 5582.7 5585.1 6213.4 6216.2 6216.2 6216.3 6224.7 6036.8 6143.2 6163.8 6256.7 6267.2 5859.0 5875.4 5876.6 5877.2 5880.2 6322.3 6347.9 6372.7 6381.2 6385.6 5855.7 5949.1 6085.2 6125.9 6159.7 6275.7 6276.6 6276.7 6277.3 6278.0 6081.3 6241.2 6248.1 6249.3 6255.0 6134.2 6191.2 6344.4 6354.3 6357.7 4750.9 4754.6 4755.2 4782.7 4789.1 4146.8 4222.1 4293.2 4301.0 4305.0 5877.3 5999.3 6015.1 6015.8 6035.1 6335.3 6336.5 6361.1 6369.5 6370.6 5696.9 5739.3 5797.8 5942.9 5955.9 5404.9 5405.4 5405.4 5412.7 5467.7 6423.9 6446.1 6447.4 6447.5 6456.0 6022.3 6116.6 6129.6 6240.7 6299.1 6381.4 6584.0 6589.3 6591.3 6593.9 6276.5 6294.1 6297.9 6299.0 6312.9 6087.7 6121.9 6158.0 6233.3 6233.8 6106.8 6140.2 6141.8 6142.0 6146.9 5837.2 6097.5 6101.4 6104.3 6107.0 5974.6 5984.1 6089.2 6099.5 6105.8 6103.9 6110.8 6145.2 6155.9 6156.1 5810.1 5940.5 5941.1 5947.4 5971.6 04:29:21 INFO - 2015-12-16 04:29:21,362 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,362 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53405.55 Median:36273.14 stddev:90633.78 (249.9%) stddev-sans-first:90801.32 04:29:21 INFO - 2015-12-16 04:29:21,362 DEBUG : BROWSER_OUTPUT: Values: 601.4 649.7 654.5 655.4 657.9 36177.5 44453.8 44870.6 45074.3 45248.5 48827.0 49478.8 49541.5 49572.8 49579.5 8311.8 8436.8 8922.1 9083.6 10441.3 10712.0 10875.6 10896.1 10898.2 10903.0 10897.7 10987.5 10991.9 10995.1 11012.9 10868.3 10938.6 10950.8 10964.3 10965.7 10696.8 10794.2 10814.9 10824.7 10825.8 45640.0 46041.3 46084.5 46106.0 46168.0 3641.4 3774.8 3846.7 4075.8 4455.2 34520.0 34826.5 34977.3 35030.8 35825.5 3894.7 3918.9 3928.6 3981.2 4081.5 302355.0 303335.0 305120.0 451920.0 455375.0 75154.6 75876.6 76137.6 76177.2 76245.6 70533.6 70643.6 70734.0 70786.6 70872.0 18308.5 18578.5 18613.3 18656.4 18660.2 14194.1 14373.7 14418.5 14452.7 14471.9 22462.0 22644.8 22705.8 22740.3 22779.4 8135.4 8384.5 8391.8 8399.0 8468.8 1014.6 1070.4 1115.7 1165.5 1172.0 30319.8 30478.0 30959.0 31618.8 31668.0 1119.4 1124.3 1131.7 1200.3 1213.0 13710.0 15057.5 15068.2 15574.5 15621.8 369595.0 375597.0 377034.0 377265.0 377660.0 60198.7 60541.0 60610.4 60632.4 60682.4 1329.2 1428.8 1432.2 1537.6 1568.8 30429.5 30513.5 30542.0 31077.1 31231.2 50636.2 50889.0 50985.2 50992.5 50992.7 280145.0 280205.0 281040.0 283328.0 284889.0 36230.0 36270.6 36617.5 36645.8 36999.0 43123.8 43834.8 44291.3 44628.8 44943.3 37079.8 37678.4 37714.4 40919.2 41382.8 40313.6 40410.8 40544.2 41471.0 41686.2 39739.6 40057.0 40123.4 40345.6 40406.6 35249.0 35736.5 36065.3 36205.3 36275.6 27092.6 27815.0 27861.3 27905.7 27945.4 04:29:21 INFO - 2015-12-16 04:29:21,362 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,362 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31800.50 Median:26852.42 stddev:106664.13 (397.2%) stddev-sans-first:106937.28 04:29:21 INFO - 2015-12-16 04:29:21,363 DEBUG : BROWSER_OUTPUT: Values: 396.1 397.9 399.4 400.5 402.2 16209.3 16692.6 19136.5 19291.4 19657.0 17943.7 18035.9 18180.6 18206.9 18684.3 5752.2 5844.9 5884.3 5900.4 5901.2 4408.9 5869.4 6002.8 6418.7 6437.1 6270.1 6350.5 6353.1 6390.8 6393.8 6275.1 6304.2 6316.7 6340.9 6347.8 5477.1 5529.8 5612.1 5621.3 5632.8 13951.5 14277.6 14332.5 23182.5 23360.2 2231.3 2291.0 2315.0 2575.3 2781.7 11063.9 11158.8 11235.9 11272.2 11306.1 1593.9 1711.2 1776.0 1813.5 1898.6 65590.3 65687.7 65979.7 66483.3 66805.0 7654.1 7790.7 7905.6 7929.1 7982.9 9466.9 9500.2 9511.3 9528.0 9532.7 6296.4 6387.7 6408.9 6433.9 6441.9 6414.7 6442.1 6446.4 6453.2 6467.5 3681.4 3728.1 3731.1 3756.8 3757.2 1262.9 1286.3 1299.7 1304.5 1338.8 502.0 529.9 533.4 534.6 534.8 11549.4 13282.9 13753.1 13768.3 13846.3 537.5 541.5 544.8 545.0 572.9 7226.7 7267.7 7518.1 7587.8 7644.1 624834.0 625912.0 645649.0 678815.0 680710.0 82932.6 85363.1 86210.0 88099.0 90234.0 572.6 583.5 584.3 584.7 586.6 12275.4 12547.0 12652.7 12697.2 12811.9 39025.2 39063.6 39331.0 39879.2 40493.6 48437.3 52735.0 53171.5 53360.7 54294.2 7615.7 7791.8 7839.3 7886.2 7990.2 11177.0 13990.2 14184.8 14238.4 14546.6 12322.1 12474.4 12558.2 12624.4 12725.6 11798.5 12137.2 12267.1 12425.8 12682.0 11865.0 12409.3 12567.9 12736.7 12789.6 7562.5 7712.7 7714.7 7717.5 7761.4 12285.6 12382.5 12448.7 12534.6 12572.9 04:29:21 INFO - 2015-12-16 04:29:21,363 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,363 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5972.03 Median:6620.17 stddev:3058.67 (46.2%) stddev-sans-first:3049.48 04:29:21 INFO - 2015-12-16 04:29:21,364 DEBUG : BROWSER_OUTPUT: Values: 1585.7 1586.4 1820.6 1840.3 1865.6 7569.7 7743.0 8170.8 8536.6 8563.6 7892.5 8028.8 8104.4 8109.1 8111.6 2450.4 2486.3 2486.3 2487.1 2487.5 2187.8 2233.8 2431.9 2467.9 2519.5 2413.6 2425.3 2437.4 2441.8 2453.7 2304.9 2324.5 2326.0 2327.8 2329.4 2422.1 2441.4 2443.5 2444.4 2446.4 8403.8 8473.0 8475.7 8479.4 8488.9 5188.6 5224.5 5245.7 5261.7 5266.9 3320.4 3330.4 3431.1 3453.8 3462.0 1082.9 1099.9 1124.6 1149.1 1186.8 11626.4 11631.1 11636.1 11640.7 11641.0 4712.3 4738.2 4744.8 4780.0 4793.8 9791.5 9831.8 9833.4 9837.2 9871.3 5228.6 5233.9 5237.7 5238.2 5238.5 4724.7 4726.6 4732.1 4733.6 4734.1 3719.8 4121.7 4150.5 4153.5 4186.7 1260.7 1281.8 1368.3 1382.3 1382.4 1186.9 1219.3 1221.5 1512.9 1538.8 7971.0 8104.8 8306.8 8340.7 8342.1 1137.5 1181.6 1220.2 1221.7 1246.3 6557.1 6585.4 6611.2 6629.1 6637.9 9939.1 9974.5 10042.3 10063.5 10073.1 9729.1 9918.0 9946.5 9959.9 9984.2 7958.1 7998.4 8025.3 8029.0 8036.2 8044.8 8114.7 8143.3 8150.3 8185.3 9060.0 9087.4 9141.5 9159.1 9223.4 9161.4 9340.9 9347.9 9442.1 9484.5 7635.4 7643.3 7711.4 7738.3 7745.7 7264.5 7382.8 8250.8 8369.0 8374.1 7608.2 7788.8 7798.8 7801.3 7943.7 7706.7 7734.3 7750.2 7825.6 7845.1 7854.4 7884.4 7962.5 7967.0 7975.3 7036.4 7093.1 7093.5 7097.8 7108.0 6994.1 7073.3 7130.5 7187.6 7208.3 04:29:21 INFO - 2015-12-16 04:29:21,364 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,364 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5379.03 Median:6125.47 stddev:1582.04 (25.8%) stddev-sans-first:1541.22 04:29:21 INFO - 2015-12-16 04:29:21,365 DEBUG : BROWSER_OUTPUT: Values: 373.4 379.7 381.3 384.2 385.5 5948.4 5991.0 6010.5 6021.5 6073.5 5917.6 5921.5 5953.6 5955.3 5960.7 4552.6 4652.6 4672.1 4673.6 4686.4 4679.1 4683.2 4685.5 4696.9 4699.0 4491.2 4541.9 4562.7 4647.3 4651.4 4586.4 4609.1 4613.5 4616.5 4621.6 4050.3 4056.5 4061.4 4062.8 4065.1 5512.7 5583.6 5593.7 5675.4 5700.3 5354.0 5355.9 5360.2 5360.8 5363.7 6225.8 6277.6 6313.7 6323.3 6334.0 4487.5 4488.9 4495.2 4575.9 4582.0 6469.7 6490.0 6521.2 6526.5 6539.3 4655.6 4657.7 4675.9 4676.6 4693.4 4718.6 4757.5 4757.5 4760.2 4762.4 5319.7 5454.1 5474.6 5477.9 5480.7 6460.4 6467.7 6469.4 6472.3 6475.4 1706.8 1728.5 1728.8 1729.0 1729.5 1155.1 1160.4 1168.6 1184.9 1185.9 6205.8 6245.3 6250.5 6251.9 6253.3 6496.6 6542.1 6552.0 6553.2 6607.4 6120.0 6195.4 6202.4 6204.2 6245.5 3632.6 3645.1 3650.5 3652.3 3652.9 6532.3 6616.8 6683.1 6797.9 6798.7 6744.4 6748.4 6761.9 6765.4 6774.9 6740.9 6748.7 6750.8 6753.8 6755.4 6467.2 6581.8 6586.3 6589.3 6591.3 6535.8 6610.6 6615.2 6619.6 6620.4 6804.1 6852.3 6853.2 6853.4 6865.3 4591.2 4679.8 4680.1 4680.5 4681.3 6665.9 6667.6 6670.5 6674.0 6676.6 6466.9 6481.7 6483.0 6487.7 6490.5 6302.1 6463.7 6468.5 6472.3 6483.2 6377.0 6378.5 6379.8 6380.4 6381.2 6499.1 6510.2 6510.5 6512.7 6516.6 6130.9 6212.5 6223.3 6224.5 6310.5 04:29:21 INFO - 2015-12-16 04:29:21,365 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,365 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15163.30 Median:4818.45 stddev:17997.84 (373.5%) stddev-sans-first:18016.50 04:29:21 INFO - 2015-12-16 04:29:21,366 DEBUG : BROWSER_OUTPUT: Values: 910.3 931.3 960.3 968.0 972.1 14319.5 14846.7 14863.0 14867.5 14874.1 15489.7 15805.1 15821.8 15824.4 15873.7 765.6 772.0 919.6 927.1 945.5 927.7 936.1 942.3 944.0 944.2 981.6 988.5 990.0 990.5 991.0 977.5 978.1 979.2 979.4 979.5 709.4 716.1 716.2 716.5 716.7 14561.6 14651.6 14703.6 14709.8 14722.9 11302.8 11368.1 11390.3 11510.5 11752.4 10420.7 10526.7 10935.4 11120.1 11129.1 3598.7 3605.2 3609.0 3614.4 3616.8 47342.5 47363.0 47721.5 47979.0 48028.8 8322.1 8329.8 8395.6 8405.1 8408.5 12681.8 12988.2 12995.0 12995.1 13035.0 7368.7 7456.2 7529.6 7562.6 7669.5 4810.4 4832.2 4845.1 4855.8 4901.7 2987.2 2997.3 3005.1 3006.6 3007.8 469.4 473.4 481.7 491.1 491.2 701.2 752.2 758.6 768.5 787.2 9406.7 9778.0 9860.3 9893.4 10025.4 601.4 651.6 694.2 766.3 801.1 4826.5 4888.1 4944.2 5031.7 5071.4 60595.1 60973.0 61554.4 67640.7 67940.3 59208.4 59925.0 60127.7 60800.4 60959.4 52384.2 52413.0 53158.7 53365.0 53934.7 9839.8 9841.7 9854.7 9910.7 10323.8 40032.4 40127.8 41135.6 41136.8 41320.0 52316.0 52740.0 53085.2 53721.0 54166.7 12573.3 12608.6 12651.2 12776.7 13142.0 13271.7 13552.4 13872.5 14083.6 14219.4 13024.2 13110.2 13247.6 13269.5 13432.0 12552.9 12671.2 12839.6 12866.7 12894.2 12035.6 12045.6 12920.7 13023.5 13279.2 10840.0 11005.0 11017.9 11154.6 11508.7 10817.5 10959.5 11240.8 11248.4 11253.3 04:29:21 INFO - 2015-12-16 04:29:21,366 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:29:21 INFO - 2015-12-16 04:29:21,367 DEBUG : BROWSER_OUTPUT: 04:29:21 INFO - 2015-12-16 04:29:21,874 INFO : Browser exited with error code: 0 04:29:21 INFO - 2015-12-16 04:29:21,891 INFO : Completed test dromaeo_css (00:18:29) 04:29:21 INFO - 2015-12-16 04:29:21,892 INFO : Starting test kraken 04:29:21 INFO - 2015-12-16 04:29:21,892 DEBUG : operating with platform_type : linux_ 04:29:21 INFO - 2015-12-16 04:29:21,892 INFO : Initialising browser for kraken test... 04:29:21 INFO - 2015-12-16 04:29:21,898 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpG4iniC/profile http://localhost:60858/getInfo.html 04:29:25 INFO - 2015-12-16 04:29:25,349 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:29:25 INFO - 2015-12-16 04:29:25,349 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:29:25 INFO - 2015-12-16 04:29:25,349 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:29:25 INFO - 2015-12-16 04:29:25,349 DEBUG : BROWSER_OUTPUT: __metrics 04:29:25 INFO - 2015-12-16 04:29:25,474 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] 04:29:26 INFO - 2015-12-16 04:29:26,467 INFO : Browser initialized. 04:29:26 INFO - 2015-12-16 04:29:26,468 INFO : Running cycle 1/1 for kraken test... 04:29:26 INFO - 2015-12-16 04:29:26,468 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpG4iniC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:29:38 INFO - 2015-12-16 04:29:38,120 DEBUG : BROWSER_OUTPUT: RSS: Main: 129241088 04:29:38 INFO - 2015-12-16 04:29:38,120 DEBUG : BROWSER_OUTPUT: 04:29:40 INFO - 2015-12-16 04:29:40,554 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60858/tests/kraken/driver.html?testName=audio-beat-detection) 04:29:41 INFO - 2015-12-16 04:29:41,123 DEBUG : BROWSER_OUTPUT: RSS: Main: 164864000 04:29:41 INFO - 2015-12-16 04:29:41,124 DEBUG : BROWSER_OUTPUT: 04:29:43 INFO - 2015-12-16 04:29:43,659 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60858/tests/kraken/driver.html?testName=audio-dft) 04:29:44 INFO - 2015-12-16 04:29:44,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 161738752 04:29:44 INFO - 2015-12-16 04:29:44,222 DEBUG : BROWSER_OUTPUT: 04:29:47 INFO - 2015-12-16 04:29:47,000 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60858/tests/kraken/driver.html?testName=audio-fft) 04:29:47 INFO - 2015-12-16 04:29:47,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 158654464 04:29:47 INFO - 2015-12-16 04:29:47,562 DEBUG : BROWSER_OUTPUT: 04:29:49 INFO - 2015-12-16 04:29:49,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60858/tests/kraken/driver.html?testName=audio-oscillator) 04:29:50 INFO - 2015-12-16 04:29:50,063 DEBUG : BROWSER_OUTPUT: RSS: Main: 160247808 04:29:50 INFO - 2015-12-16 04:29:50,063 DEBUG : BROWSER_OUTPUT: 04:29:52 INFO - 2015-12-16 04:29:52,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60858/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:29:52 INFO - 2015-12-16 04:29:52,738 DEBUG : BROWSER_OUTPUT: RSS: Main: 156667904 04:29:52 INFO - 2015-12-16 04:29:52,738 DEBUG : BROWSER_OUTPUT: 04:29:55 INFO - 2015-12-16 04:29:55,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60858/tests/kraken/driver.html?testName=imaging-darkroom) 04:29:56 INFO - 2015-12-16 04:29:56,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 156459008 04:29:56 INFO - 2015-12-16 04:29:56,167 DEBUG : BROWSER_OUTPUT: 04:29:59 INFO - 2015-12-16 04:29:59,479 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60858/tests/kraken/driver.html?testName=imaging-desaturate) 04:30:00 INFO - 2015-12-16 04:30:00,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 157376512 04:30:00 INFO - 2015-12-16 04:30:00,041 DEBUG : BROWSER_OUTPUT: 04:30:02 INFO - 2015-12-16 04:30:02,728 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60858/tests/kraken/driver.html?testName=json-parse-financial) 04:30:03 INFO - 2015-12-16 04:30:03,295 DEBUG : BROWSER_OUTPUT: RSS: Main: 157208576 04:30:03 INFO - 2015-12-16 04:30:03,295 DEBUG : BROWSER_OUTPUT: 04:30:04 INFO - 2015-12-16 04:30:04,774 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60858/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:30:05 INFO - 2015-12-16 04:30:05,336 DEBUG : BROWSER_OUTPUT: RSS: Main: 160923648 04:30:05 INFO - 2015-12-16 04:30:05,336 DEBUG : BROWSER_OUTPUT: 04:30:07 INFO - 2015-12-16 04:30:07,053 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:30:07 INFO - 2015-12-16 04:30:07,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 160505856 04:30:07 INFO - 2015-12-16 04:30:07,655 DEBUG : BROWSER_OUTPUT: 04:30:09 INFO - 2015-12-16 04:30:09,570 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:30:10 INFO - 2015-12-16 04:30:10,136 DEBUG : BROWSER_OUTPUT: RSS: Main: 165728256 04:30:10 INFO - 2015-12-16 04:30:10,136 DEBUG : BROWSER_OUTPUT: 04:30:12 INFO - 2015-12-16 04:30:12,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:30:13 INFO - 2015-12-16 04:30:13,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 170176512 04:30:13 INFO - 2015-12-16 04:30:13,041 DEBUG : BROWSER_OUTPUT: 04:30:15 INFO - 2015-12-16 04:30:15,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:30:16 INFO - 2015-12-16 04:30:16,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 167002112 04:30:16 INFO - 2015-12-16 04:30:16,024 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,632 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60858/tests/kraken/driver.html?testName=ai-astar) 04:30:17 INFO - 2015-12-16 04:30:17,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 226852864 04:30:17 INFO - 2015-12-16 04:30:17,880 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,883 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:30:17 INFO - 2015-12-16 04:30:17,883 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:30:17 INFO - 2015-12-16 04:30:17,884 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:30:17 INFO - 2015-12-16 04:30:17,884 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:30:17 INFO - 2015-12-16 04:30:17,884 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;111;103;112;110;110;103;111;110;109 04:30:17 INFO - 2015-12-16 04:30:17,884 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;123;147;164;125;145;148;122;158;122 04:30:17 INFO - 2015-12-16 04:30:17,884 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;159;159;160;161;158;161;161;160 04:30:17 INFO - 2015-12-16 04:30:17,884 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;72;159;70;71;72;71;150;70 04:30:17 INFO - 2015-12-16 04:30:17,884 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;133;88;88;89;130;88;89;137 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;109;109;109;109;109;109 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;145;145;145;145;145;145 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;96;95;95;96;95;96 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;54;54;54;54;54;57;62;52;54 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;63;63;63;64;63;66;64 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;77;77;76;77;78;78;78;78;77 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;109;113;112;112;111;107;116;115;116;121 04:30:17 INFO - 2015-12-16 04:30:17,885 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;136;135;145;137;135;135;136;136;132 04:30:17 INFO - 2015-12-16 04:30:17,886 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;60;60;61;60;62;60;61;60;60;60 04:30:17 INFO - 2015-12-16 04:30:17,886 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:30:17 INFO - 2015-12-16 04:30:17,886 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:30:17 INFO - 2015-12-16 04:30:17,886 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,862 04:30:17 INFO - 2015-12-16 04:30:17,886 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:30:17 INFO - 2015-12-16 04:30:17,886 DEBUG : BROWSER_OUTPUT: __startTimestamp1450269017881__endTimestamp 04:30:17 INFO - 2015-12-16 04:30:17,886 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:3.20 (2.9%) stddev-sans-first:3.38 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: Values: 108.0 111.0 103.0 112.0 110.0 110.0 103.0 111.0 110.0 109.0 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.10 Median:147.50 stddev:16.59 (11.2%) stddev-sans-first:16.57 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: Values: 157.0 123.0 147.0 164.0 125.0 145.0 148.0 122.0 158.0 122.0 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 04:30:17 INFO - 2015-12-16 04:30:17,890 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 159.0 159.0 160.0 161.0 158.0 161.0 161.0 160.0 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.80 Median:71.00 stddev:35.23 (49.6%) stddev-sans-first:36.90 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 72.0 159.0 70.0 71.0 72.0 71.0 150.0 70.0 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.00 Median:88.50 stddev:21.69 (24.5%) stddev-sans-first:22.49 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 133.0 88.0 88.0 89.0 130.0 88.0 89.0 137.0 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:30:17 INFO - 2015-12-16 04:30:17,891 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 96.0 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.00 stddev:2.82 (5.2%) stddev-sans-first:2.92 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: Values: 53.0 54.0 54.0 54.0 54.0 54.0 57.0 62.0 52.0 54.0 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.50 Median:63.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 63.0 63.0 63.0 64.0 63.0 66.0 64.0 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:77.50 stddev:0.79 (1.0%) stddev-sans-first:0.71 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: Values: 76.0 77.0 77.0 76.0 77.0 78.0 78.0 78.0 78.0 77.0 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.20 Median:114.00 stddev:3.99 (3.5%) stddev-sans-first:3.94 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: Values: 109.0 113.0 112.0 112.0 111.0 107.0 116.0 115.0 116.0 121.0 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.20 Median:136.00 stddev:3.36 (2.5%) stddev-sans-first:3.54 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: Values: 135.0 136.0 135.0 145.0 137.0 135.0 135.0 136.0 136.0 132.0 04:30:17 INFO - 2015-12-16 04:30:17,892 DEBUG : BROWSER_OUTPUT: 04:30:17 INFO - 2015-12-16 04:30:17,893 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 04:30:17 INFO - 2015-12-16 04:30:17,893 DEBUG : BROWSER_OUTPUT: Values: 60.0 60.0 61.0 60.0 62.0 60.0 61.0 60.0 60.0 60.0 04:30:17 INFO - 2015-12-16 04:30:17,893 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:30:17 INFO - 2015-12-16 04:30:17,893 DEBUG : BROWSER_OUTPUT: 04:30:18 INFO - 2015-12-16 04:30:18,494 INFO : Browser exited with error code: 0 04:30:18 INFO - 2015-12-16 04:30:18,542 INFO : Completed test kraken (00:00:56) 04:30:18 INFO - 2015-12-16 04:30:18,542 INFO : Starting test v8_7 04:30:18 INFO - 2015-12-16 04:30:18,542 DEBUG : operating with platform_type : linux_ 04:30:18 INFO - 2015-12-16 04:30:18,542 INFO : Initialising browser for v8_7 test... 04:30:18 INFO - 2015-12-16 04:30:18,548 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpy6SDk7/profile http://localhost:60858/getInfo.html 04:30:22 INFO - 2015-12-16 04:30:22,006 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:30:22 INFO - 2015-12-16 04:30:22,006 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:30:22 INFO - 2015-12-16 04:30:22,006 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:30:22 INFO - 2015-12-16 04:30:22,006 DEBUG : BROWSER_OUTPUT: __metrics 04:30:22 INFO - 2015-12-16 04:30:22,163 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] 04:30:23 INFO - 2015-12-16 04:30:23,118 INFO : Browser initialized. 04:30:23 INFO - 2015-12-16 04:30:23,119 INFO : Running cycle 1/1 for v8_7 test... 04:30:23 INFO - 2015-12-16 04:30:23,119 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpy6SDk7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:30:34 INFO - 2015-12-16 04:30:34,777 DEBUG : BROWSER_OUTPUT: RSS: Main: 131084288 04:30:34 INFO - 2015-12-16 04:30:34,777 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60858/tests/v8_7/run.html (next: http://localhost:60858/tests/v8_7/run.html) 04:30:56 INFO - 2015-12-16 04:30:56,351 DEBUG : BROWSER_OUTPUT: RSS: Main: 416514048 04:30:56 INFO - 2015-12-16 04:30:56,351 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,351 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:30:56 INFO - 2015-12-16 04:30:56,351 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:30:56 INFO - 2015-12-16 04:30:56,351 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:30:56 INFO - 2015-12-16 04:30:56,351 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |0;Richards;163.05233980107616 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;171.76228100309172 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.1120448179272 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5005 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.073793755913 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |5;Earley;557.1030640668523 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |6;Boyer;10547.368421052632 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |7;RegExp;23857.14285714286 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |8;Splay;523.4864300626305 04:30:56 INFO - 2015-12-16 04:30:56,352 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 04:30:56 INFO - 2015-12-16 04:30:56,353 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:30:56 INFO - 2015-12-16 04:30:56,353 DEBUG : BROWSER_OUTPUT: __startTimestamp1450269056349__endTimestamp 04:30:56 INFO - 2015-12-16 04:30:56,353 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,353 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:30:56 INFO - 2015-12-16 04:30:56,353 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:30:56 INFO - 2015-12-16 04:30:56,353 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,353 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.05 Median:163.05 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,354 DEBUG : BROWSER_OUTPUT: Values: 163.1 04:30:56 INFO - 2015-12-16 04:30:56,354 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,354 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:171.76 Median:171.76 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,354 DEBUG : BROWSER_OUTPUT: Values: 171.8 04:30:56 INFO - 2015-12-16 04:30:56,354 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,354 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.11 Median:280.11 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,355 DEBUG : BROWSER_OUTPUT: Values: 280.1 04:30:56 INFO - 2015-12-16 04:30:56,355 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,355 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5005.00 Median:5005.00 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,355 DEBUG : BROWSER_OUTPUT: Values: 5005.0 04:30:56 INFO - 2015-12-16 04:30:56,355 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,355 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.07 Median:946.07 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,356 DEBUG : BROWSER_OUTPUT: Values: 946.1 04:30:56 INFO - 2015-12-16 04:30:56,356 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,356 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:557.10 Median:557.10 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,356 DEBUG : BROWSER_OUTPUT: Values: 557.1 04:30:56 INFO - 2015-12-16 04:30:56,356 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,356 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10547.37 Median:10547.37 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,357 DEBUG : BROWSER_OUTPUT: Values: 10547.4 04:30:56 INFO - 2015-12-16 04:30:56,357 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,357 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23857.14 Median:23857.14 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,357 DEBUG : BROWSER_OUTPUT: Values: 23857.1 04:30:56 INFO - 2015-12-16 04:30:56,357 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,357 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:523.49 Median:523.49 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,357 DEBUG : BROWSER_OUTPUT: Values: 523.5 04:30:56 INFO - 2015-12-16 04:30:56,358 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,358 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 04:30:56 INFO - 2015-12-16 04:30:56,358 DEBUG : BROWSER_OUTPUT: Values: 4830.9 04:30:56 INFO - 2015-12-16 04:30:56,358 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:30:56 INFO - 2015-12-16 04:30:56,358 DEBUG : BROWSER_OUTPUT: 04:30:56 INFO - 2015-12-16 04:30:56,974 INFO : Browser exited with error code: 0 04:30:56 INFO - 2015-12-16 04:30:56,987 INFO : Completed test v8_7 (00:00:38) 04:30:57 INFO - 2015-12-16 04:30:57,420 INFO : Completed test suite (00:20:04) 04:30:57 INFO - 2015-12-16 04:30:57,420 DEBUG : Working with test: dromaeo_css 04:30:57 INFO - 2015-12-16 04:30:57,420 DEBUG : Generating results file: dromaeo_css 04:30:57 INFO - 2015-12-16 04:30:57,421 DEBUG : Working with test: kraken 04:30:57 INFO - 2015-12-16 04:30:57,421 DEBUG : Generating results file: kraken 04:30:57 INFO - 2015-12-16 04:30:57,421 INFO : javascript benchmark 04:30:57 INFO - 2015-12-16 04:30:57,422 DEBUG : Working with test: v8_7 04:30:57 INFO - 2015-12-16 04:30:57,422 DEBUG : Generating results file: v8_7 04:30:57 INFO - 2015-12-16 04:30:57,422 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:30:57 INFO - 2015-12-16 04:30:57,483 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 04:30:57 INFO - 2015-12-16 04:30:57,483 DEBUG : process_Request line: dromaeo_css 7364.41 graph.html#tests=[[72,53,35]] 04:30:57 INFO - 2015-12-16 04:30:57,483 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:30:57 INFO - 2015-12-16 04:30:57,514 DEBUG : process_Request line: kraken 1454.50 graph.html#tests=[[232,53,35]] 04:30:57 INFO - 2015-12-16 04:30:57,514 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:30:57 INFO - 2015-12-16 04:30:57,553 DEBUG : process_Request line: v8_7 23302.31 graph.html#tests=[[230,53,35]] 04:30:57 INFO - 2015-12-16 04:30:57,554 INFO : javascript benchmark 04:30:57 INFO - 2015-12-16 04:30:57,558 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3380.03668954348, 3431.6225215226336, 3443.6384179661136, 3449.5170676105367, 3485.3900567400697, 5719.456651618095, 5779.190913272135, 5788.507976821965, 5791.305043394788, 5798.3042034955815, 5762.740676669549, 5806.0, 5819.0, 5839.532837373011, 5858.5606079543995, 5048.974755126219, 5224.529792318691, 5271.789128434877, 5342.679439233659, 5361.276227709268, 5199.896002079956, 5207.817726379578, 5224.451432599561, 5232.372115346145, 5240.135377662681, 5241.2400201970695, 5313.0967735485065, 5372.892542149155, 5390.245365648812, 5404.243405923174, 5297.205419187114, 5311.840644780663, 5316.813911513098, 5319.521243088102, 5322.0, 5047.293378926953, 5058.418281897592, 5177.119889618773, 5208.140656791663, 5234.398044224925, 5548.639338442988, 5570.6657600544095, 5578.3584887738025, 5582.748776305076, 5585.134304182858, 6213.440790328847, 6216.160818289518, 6216.160818289563, 6216.347283535209, 6224.688765561749, 6036.849078773066, 6143.232095988002, 6163.753449862, 6256.718447669866, 6267.1539342189135, 5859.033259512175, 5875.441833025813, 5876.588638795286, 5877.235959325303, 5880.235569375954, 6322.272938612026, 6347.873042539216, 6372.745090196433, 6381.202349706286, 6385.616862988236, 5855.736491857877, 5949.10763385487, 6085.208922840003, 6125.947807484543, 6159.722812473449, 6275.6548389839, 6276.592021518587, 6276.6547839868335, 6277.340879207709, 6278.0, 6081.331053584191, 6241.18864547615, 6248.094026366151, 6249.343818899041, 6254.9687251563455, 6134.215925598752, 6191.164192834, 6344.397282258178, 6354.269259035178, 6357.650329231937, 4750.904981900412, 4754.643401744883, 4755.167845627001, 4782.736949467743, 4789.090072886141, 4146.813393397304, 4222.0711443483, 4293.162833247549, 4300.978495107567, 4304.978475107604, 5877.265341832271, 5999.340072592011, 6015.0977353396465, 6015.849603759941, 6035.064564992345, 6335.334789847092, 6336.493080553545, 6361.077643741632, 6369.458596019297, 6370.617762934239, 5696.857578560569, 5739.339975902742, 5797.768089276466, 5942.851428714231, 5955.880882382328, 5404.91892621611, 5405.351357837006, 5405.351357837085, 5412.675239485643, 5467.72661366938, 6423.935760642427, 6446.065320528497, 6447.355264473609, 6447.516436267299, 6455.9677201613695, 6022.307434644897, 6116.602420842631, 6129.601575897553, 6240.719167637377, 6299.086632438362, 6381.361863813581, 6583.96708016476, 6589.27517973013, 6591.307912669293, 6593.901091483727, 6276.466500347522, 6294.087357333069, 6297.874042519033, 6298.968505157445, 6312.905306420499, 6087.695615219132, 6121.878680213292, 6157.9692101540995, 6233.314335423009, 6233.812985610439, 6106.786262480792, 6140.17107690456, 6141.7543298267565, 6142.0, 6146.907796383147, 5837.241158649348, 6097.4817140543555, 6101.389861013863, 6104.298005729398, 6107.0, 5974.641521508724, 5984.102384642164, 6089.238845144357, 6099.4815440688035, 6105.755769769337, 6103.908441373294, 6110.816675499742, 6145.201123853871, 6155.876882462236, 6156.107364432043, 5810.099434587646, 5940.495057919952, 5941.1385349125, 5947.375525569926, 5971.641701497924], "unit": "ms", "name": "dojo.html", "value": 5738.337980828025}, {"lowerIsBetter": false, "replicates": [601.4286427893502, 649.6913965866213, 654.4666097130842, 655.4363247607054, 657.9144711187545, 36177.45733813992, 44453.77773111134, 44870.55129448704, 45074.32388514175, 45248.54751452484, 48827.0, 49478.75260623692, 49541.50458495414, 49572.75213623936, 49579.50420495794, 8311.83376332473, 8436.789080272982, 8922.06318336575, 9083.591238394256, 10441.321314114559, 10712.035916767489, 10875.564977400894, 10896.128309735202, 10898.237123401326, 10903.0, 10897.727556811104, 10987.450627468634, 10991.890081099167, 10995.120390368751, 11012.889871101266, 10868.347899126038, 10938.617148399768, 10950.780984380348, 10964.342139471577, 10965.671029869116, 10696.78606427867, 10794.2444028918, 10814.891851081467, 10824.675259742142, 10825.83761243582, 45640.0, 46041.30938035932, 46084.53915460836, 46106.0, 46168.0, 3641.417373220272, 3774.8112594370446, 3846.692264618852, 4075.7758323292205, 4455.198064348416, 34520.0, 34826.47760283598, 34977.30045399103, 35030.82484587586, 35825.46261806075, 3894.6884249260274, 3918.8628398005935, 3928.5678575356974, 3981.163955569305, 4081.5306239782212, 302355.0, 303335.0, 305120.0, 451920.0, 455375.0, 75154.62422687851, 75876.62061689656, 76137.61931190308, 76177.23822761812, 76245.61877190579, 70533.64733176403, 70643.64678176577, 70734.0, 70786.64606676933, 70872.0, 18308.542286442946, 18578.535536611693, 18613.34853280156, 18656.440306790817, 18660.16029278686, 14194.148351098967, 14373.712525749635, 14418.495352662607, 14452.710945781026, 14471.855281447051, 22461.989210485568, 22644.773552264265, 22705.772942270367, 22740.317790466313, 22779.430514236945, 8135.3898457615915, 8384.538850363166, 8391.790205244795, 8398.958005210057, 8468.788280293042, 1014.624588902111, 1070.3952266969138, 1115.7210697325668, 1165.5454372794618, 1171.9531218751324, 30319.848400757855, 30478.0, 30959.0, 31618.841905790323, 31668.0, 1119.3731510354228, 1124.2630136439059, 1131.7227279316621, 1200.2918278215895, 1212.9878701213051, 13710.0, 15057.472988445352, 15068.246587670792, 15574.532764017098, 15621.765673514907, 369595.0, 375597.0, 377034.0, 377265.0, 377660.0, 60198.699006504685, 60541.0, 60610.39389606047, 60632.393676062675, 60682.39317606856, 1329.2157626999924, 1428.8071110400172, 1432.1979691372865, 1537.5771662792822, 1568.8431156884449, 30429.543556846224, 30513.542296865122, 30542.0, 31077.067687969397, 31231.219219519695, 50636.24045639245, 50889.0, 50985.23522147097, 50992.49007509877, 50992.74503627458, 280145.0, 280205.0, 281040.0, 283328.0, 284889.0, 36230.0, 36270.637293627784, 36617.45073823842, 36645.816770915975, 36999.0, 43123.784381077894, 43834.78082609694, 44291.33562996622, 44628.77685611551, 44943.32585011293, 37079.82094932715, 37678.434823478216, 37714.43428348522, 40919.1816163681, 41382.79308603438, 40313.59686403099, 40410.79794601008, 40544.1891162181, 41471.0, 41686.16627667369, 39739.60260397359, 40057.0, 40123.39814902837, 40345.59654403418, 40406.595934040284, 35249.0, 35736.463953040206, 36065.27869442544, 36205.27589448249, 36275.63724362723, 27092.593611096243, 27815.0, 27861.303467413476, 27905.720942791126, 27945.441091177658], "unit": "ms", "name": "ext.html", "value": 20991.615837468704}, {"lowerIsBetter": false, "replicates": [396.0692372923628, 397.8786470126612, 399.41286309125593, 400.47737702298497, 402.23173738160114, 16209.270582823772, 16692.58268543287, 19136.521586960334, 19291.421257362268, 19657.0, 17943.730844037316, 18035.90982045088, 18180.63638727228, 18206.908965455154, 18684.346047888328, 5752.165935939286, 5844.853878653046, 5884.323302820188, 5900.409959004087, 5901.203337549418, 4408.86773396797, 5869.383714709958, 6002.789902353418, 6418.743250269984, 6437.066625339323, 6270.122182894398, 6350.460210882081, 6353.142325786029, 6390.808275751735, 6393.7762178323765, 6275.058741188813, 6304.243490781089, 6316.684165791692, 6340.936590634104, 6347.777827776005, 5477.123660214346, 5529.834104976857, 5612.102063669793, 5621.269234999424, 5632.802851900205, 13951.511697090553, 14277.643058923506, 14332.498362557364, 23182.53634927309, 23360.18239361614, 2231.319447568491, 2291.014863608648, 2314.953700925989, 2575.291794756457, 2781.680106787725, 11063.889361106367, 11158.776824463464, 11235.943820280847, 11272.210945233837, 11306.095512358992, 1593.8804589655822, 1711.212842092639, 1775.9911200444044, 1813.4831573001632, 1898.5918027623989, 65590.3440965589, 65687.67156164236, 65979.67010164919, 66483.3351666482, 66804.99999999952, 7654.081510218698, 7790.688372465038, 7905.56519391439, 7929.1277959424415, 7982.920170798334, 9466.857997130048, 9500.23998080152, 9511.286653500876, 9528.047195280555, 9532.666356677482, 6296.401841825019, 6387.744490220339, 6408.871822563523, 6433.935660643334, 6441.903371449432, 6414.679266036679, 6442.130312407859, 6446.419822216023, 6453.193350831146, 6467.51493637979, 3681.355762741563, 3728.105254738843, 3731.1045349116016, 3756.8166027701186, 3757.1734218471893, 1262.949482020709, 1286.3053950866615, 1299.7205600795874, 1304.4847285322435, 1338.8460327062319, 501.98745031374506, 529.8807768252112, 533.3679589686234, 534.5856960855368, 534.8448949804591, 11549.36478493691, 13282.867171328164, 13753.106048106842, 13768.311584420739, 13846.30768461573, 537.5430883748783, 541.4531323363432, 544.7984245829068, 545.0135255188122, 572.8510587247346, 7226.674799633924, 7267.745628902963, 7518.097828260644, 7587.848243035109, 7644.12092609357, 624834.0, 625912.0, 645649.0, 678815.0, 680710.0, 82932.58533707293, 85363.14636853676, 86210.0, 88099.0, 90234.0, 572.6478215897276, 583.5185971573469, 584.3046774338609, 584.7485581200125, 586.642148289543, 12275.386230688251, 12547.0, 12652.683682908, 12697.174683645533, 12811.87188128144, 39025.21949560936, 39063.609363906, 39330.98818661264, 39879.20241595094, 40493.59506404898, 48437.27344089771, 52735.0, 53171.46828531665, 53360.73319633377, 54294.18558721701, 7615.657295421608, 7791.766247012599, 7839.25527074927, 7886.171951944963, 7990.200979901963, 11177.0, 13990.230537320138, 14184.787228191792, 14238.35927383291, 14546.636334091732, 12322.137450378388, 12474.376281185723, 12558.183718058297, 12624.3687815607, 12725.61823145307, 11798.469068891749, 12137.21108127969, 12267.079969002181, 12425.813612795635, 12682.0, 11865.0, 12409.255444672988, 12567.87432125667, 12736.745265094462, 12789.61631151067, 7562.470627056054, 7712.691492340244, 7714.6914123434435, 7717.498362606412, 7761.417893658111, 12285.57000504978, 12382.504699811907, 12448.688782780502, 12534.623961281177, 12572.874271257171], "unit": "ms", "name": "jquery.html", "value": 8052.706633805501}, {"lowerIsBetter": false, "replicates": [1585.7224985627513, 1586.4050980882162, 1820.5903671673866, 1840.300685739419, 1865.608222273322, 7569.697212111509, 7742.961285193566, 8170.754877353674, 8536.573171341439, 8563.61463734132, 7892.526448413085, 8028.799280018003, 8104.432689711723, 8109.148539403366, 8111.553864537448, 2450.4363996280867, 2486.3038349262238, 2486.316263027664, 2487.141936032066, 2487.5398051360467, 2187.824974002076, 2233.810126139272, 2431.9392015199674, 2467.864267465289, 2519.483505881299, 2413.6379543068506, 2425.2650917586893, 2437.4393889405446, 2441.816863735227, 2453.7178224504232, 2304.873231972241, 2324.465372964211, 2325.9534809303805, 2327.8254130940245, 2329.4176455886027, 2422.067504010951, 2441.389652586853, 2443.486867757773, 2444.4133407982135, 2446.388402899275, 8403.789905252357, 8473.0, 8475.74572762818, 8479.406441549094, 8488.915110848875, 5188.610854185951, 5224.477552244745, 5245.6852588844795, 5261.710605916673, 5266.947330526722, 3320.4023275810346, 3330.400527904976, 3431.0564594736397, 3453.8100404477746, 3461.978716278679, 1082.9458527073614, 1099.8845121262232, 1124.5670416889557, 1149.0807354116673, 1186.7507823357018, 11626.418679066097, 11631.127665425127, 11636.12729045325, 11640.708982275426, 11641.0, 4712.269598212282, 4738.1708201064675, 4744.833930812405, 4780.0, 4793.832215872428, 9791.46146961924, 9831.80336393268, 9833.360831545926, 9837.163841073445, 9871.259655525864, 5228.55557277628, 5233.895322093613, 5237.685738855681, 5238.214267859776, 5238.476152384807, 4724.669273150916, 4726.598239149711, 4732.100900828831, 4733.573978341966, 4734.053189362141, 3719.814009299524, 4121.690873184523, 4150.481189851269, 4153.522344930311, 4186.7278626889765, 1260.7352455984346, 1281.7949128139453, 1368.3021658954005, 1382.2535830651536, 1382.3641125082574, 1186.9287842729464, 1219.3354621731178, 1221.5175005872632, 1512.9319180636899, 1538.8230353509266, 7970.960145199353, 8104.824800403909, 8306.8338633227, 8340.749777506684, 8342.124076971952, 1137.4938152522213, 1181.5982565927625, 1220.2190598017276, 1221.6988906814215, 1246.3394400967502, 6557.147570815859, 6585.440237579764, 6611.23970743361, 6629.105070815378, 6637.933620663828, 9939.055789699967, 9974.501274936225, 10042.347247428894, 10063.5471403787, 10073.143782778547, 9729.124378805942, 9918.0, 9946.452945087945, 9959.900400996043, 9984.15134713543, 7958.124606293303, 7998.4001199910235, 8025.2777250047775, 8029.0, 8036.196380362034, 8044.758657240292, 8114.675412983414, 8143.307818835466, 8150.347972162094, 8185.263326300662, 9059.95470022672, 9087.409318394282, 9141.451512909247, 9159.084091590788, 9223.44659320443, 9161.404508706913, 9340.906590934004, 9347.859782103138, 9442.10300021497, 9484.478353690612, 7635.350995165473, 7643.312101910855, 7711.421643376882, 7738.342240909586, 7745.65144568507, 7264.491485596169, 7382.815429614303, 8250.752477425687, 8369.037560680355, 8374.078851326227, 7608.2011388805595, 7788.84422311562, 7798.766037018899, 7801.3368863644955, 7943.682252709827, 7706.691732330644, 7734.265244801735, 7750.186230445722, 7825.608719564113, 7845.137034926268, 7854.410919181199, 7884.448088633741, 7962.482438641469, 7967.04395472547, 7975.282224599814, 7036.366726994595, 7093.113360829896, 7093.467989900882, 7097.751578694927, 7107.964460177666, 6994.125734283215, 7073.292670732885, 7130.5365151265005, 7187.640617968976, 7208.27917208275], "unit": "ms", "name": "mootools.html", "value": 4920.0925750304505}, {"lowerIsBetter": false, "replicates": [373.3690063792191, 379.7360834220218, 381.27177091754754, 384.1721091048789, 385.458430904579, 5948.405159484049, 5990.970045149774, 6010.549208809346, 6021.488173505258, 6073.5444841636945, 5917.5561832862495, 5921.496672782819, 5953.642781433117, 5955.34491205967, 5960.7317670704815, 4552.635789136878, 4652.604528615072, 4672.0889426561835, 4673.556012178855, 4686.414198225222, 4679.087577922306, 4683.157031734287, 4685.508021657711, 4696.882577935545, 4699.036697477009, 4491.236489796726, 4541.886452838672, 4562.726236425809, 4647.256438969748, 4651.4418269807675, 4586.449626044862, 4609.1473077480605, 4613.469451013143, 4616.49218585957, 4621.560951709615, 4050.311447054008, 4056.5334986476637, 4061.411095391152, 4062.7562346259024, 4065.0853557949226, 5512.724363781835, 5583.58123140762, 5593.720313984284, 5675.404082571312, 5700.344460387067, 5354.036273470773, 5355.866103347447, 5360.16917377807, 5360.758765855507, 5363.704996225206, 6225.844353891143, 6277.560570760049, 6313.71588278524, 6323.336049714806, 6333.9683301583655, 4487.483939346984, 4488.8877778055485, 4495.168393847149, 4575.862724118282, 4582.037772067836, 6469.74121035158, 6489.967550162266, 6521.18485189356, 6526.477881769447, 6539.280679125292, 4655.627549796008, 4657.673962822638, 4675.929861052087, 4676.6492513060975, 4693.389859318267, 4718.598919091915, 4757.500462451462, 4757.548032936935, 4760.238361862154, 4762.404699412574, 5319.7340132993195, 5454.072807622713, 5474.589405794582, 5477.890442191214, 5480.725963701799, 6460.353964603502, 6467.741290348334, 6469.417752402306, 6472.3204063573585, 6475.417212450902, 1706.7781188445422, 1728.5073753980055, 1728.8184740602055, 1729.0, 1729.481155653299, 1155.1451925575182, 1160.4081918221768, 1168.5617893290016, 1184.8933595976403, 1185.8873407026492, 6205.782797602063, 6245.250569931637, 6250.499960003189, 6251.874962500725, 6253.312135665074, 6496.610203387811, 6542.084108224757, 6552.0, 6553.246376666649, 6607.372299631527, 6120.0, 6195.411435913671, 6202.379762023852, 6204.224471941007, 6245.531585131133, 3632.636736326399, 3645.0705070206927, 3650.543682039745, 3652.324320000755, 3652.945205821915, 6532.346765323429, 6616.768413105518, 6683.064370988066, 6797.932020679828, 6798.66006699663, 6744.393004629607, 6748.392644661906, 6761.932380676229, 6765.391114799593, 6774.932250677529, 6740.932590674129, 6748.696308666122, 6750.831229219309, 6753.797386078426, 6755.3582409671, 6467.159269294962, 6581.80254592363, 6586.308437614173, 6589.341065893373, 6591.307912669293, 6535.803925882231, 6610.603363797995, 6615.239247486601, 6619.602823830585, 6620.3710647488415, 6804.11546498952, 6852.280510546321, 6853.17761868579, 6853.417459515797, 6865.313468653095, 4591.2194926863185, 4679.766011699469, 4680.134175177603, 4680.485146633936, 4681.297805329092, 6665.900011499928, 6667.566608170453, 6670.466362691071, 6673.9666301668185, 6676.56602320867, 6466.902996454963, 6481.708323125565, 6483.0, 6487.708053137714, 6490.513211509061, 6302.1492098566105, 6463.676816159079, 6468.5472016958365, 6472.320406357265, 6483.189601299838, 6377.043443483514, 6378.52161087911, 6379.840503987251, 6380.425761681471, 6381.234251889618, 6499.057636642755, 6510.153680021567, 6510.47916166715, 6512.706928188338, 6516.609003459807, 6130.938690613037, 6212.471939884979, 6223.253209614875, 6224.470919971853, 6310.5267104968225], "unit": "ms", "name": "prototype.html", "value": 4884.481739897131}, {"lowerIsBetter": false, "replicates": [910.2990697163184, 931.2922179143849, 960.2941837749253, 967.9516024198788, 972.1299437003877, 14319.498817541387, 14846.703065938687, 14863.0, 14867.479638212664, 14874.10755354678, 15489.69020619587, 15805.130717810545, 15821.762673559877, 15824.446144384949, 15873.682526349467, 765.5789315876274, 772.0195351903079, 919.5769945824912, 927.1053433436732, 945.465811816325, 927.6567669962124, 936.0826390137669, 942.29328003997, 943.9622415103388, 944.1596978688972, 981.5730157381527, 988.4958671077766, 990.0495524296684, 990.4849478271294, 991.028791784052, 977.5307852230933, 978.0708327089294, 979.1970584121024, 979.427035184418, 979.5298256836687, 709.3722055980443, 716.1012928774406, 716.2407847681475, 716.4912911832605, 716.7491378017704, 14561.635959101108, 14651.560453186315, 14703.55889323322, 14709.779353309708, 14722.926385368111, 11302.830457543143, 11368.147388945863, 11390.316581005167, 11510.482028308747, 11752.353620550866, 10420.739481512946, 10526.684199473952, 10935.398553079578, 11120.054795342325, 11129.054030407426, 3598.6941110005682, 3605.2068544920076, 3609.043603445089, 3614.4397618369194, 3616.801075940822, 47342.526574734155, 47363.0, 47721.52278477205, 47979.0, 48028.75985620049, 8322.084570697218, 8329.833403331899, 8395.62219700115, 8405.117462666454, 8408.537530435884, 12681.809772853414, 12988.155769874928, 12995.025373097056, 12995.090343675853, 13035.02237332204, 7368.742094026683, 7456.1798202197715, 7529.623518824037, 7562.621868906643, 7669.501482403626, 4810.350602668665, 4832.202686556749, 4845.079434907357, 4855.830045948375, 4901.656884018084, 2987.1635941936293, 2997.2656699108857, 3005.0834495478653, 3006.6091408116804, 3007.8345690987207, 469.4343316303914, 473.36096270035176, 481.72300926967137, 491.13805271748566, 491.22386629126606, 701.1586096684, 752.1763668782675, 758.6206896551724, 768.4966347042696, 787.2245837849714, 9406.670766523139, 9777.951110244403, 9860.260480463994, 9893.389766216147, 10025.395936650248, 601.3955974245855, 651.5536857252703, 694.2398074108843, 766.3141488367879, 801.054755388647, 4826.541478559601, 4888.120138375056, 4944.233643785219, 5031.698098114051, 5071.391433027987, 60595.09107363393, 60973.0, 61554.38445615487, 67640.6617966907, 67940.3205967944, 59208.407915921154, 59925.0, 60127.69936150291, 60800.391996079474, 60959.39040609626, 52384.214236785716, 52413.0, 53158.73420632872, 53365.0, 53934.730326348115, 9839.827162333251, 9841.655542055994, 9854.650873190812, 9910.683957080322, 10323.793524129325, 40032.3995140079, 40127.79936100301, 41135.58864411317, 41136.79431602823, 41319.96700082522, 52316.0, 52740.0, 53085.20372194343, 53721.0, 54166.729166353914, 12573.308468034345, 12608.558700445441, 12651.240925544496, 12776.680582985127, 13142.0, 13271.734565308832, 13552.390142443417, 13872.514461993782, 14083.577492675236, 14219.360128794022, 13024.153430027018, 13110.213387196798, 13247.602571922858, 13269.469221231042, 13432.0, 12552.937235313764, 12671.239725616493, 12839.550615728778, 12866.67833304175, 12894.161879477804, 12035.578754743541, 12045.578404755792, 12920.676983075498, 13023.47906083746, 13279.203247805162, 10840.024397804236, 11005.0, 11017.889821102008, 11154.553817847196, 11508.654740357802, 10817.45912704346, 10959.452027398758, 11240.830869985179, 11248.381339026431, 11253.324800511995], "unit": "ms", "name": "yui.html", "value": 6842.67670388211}], "name": "dromaeo_css", "value": 7364.412873288031}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 111.0, 103.0, 112.0, 110.0, 110.0, 103.0, 111.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.7}, {"lowerIsBetter": true, "replicates": [157.0, 123.0, 147.0, 164.0, 125.0, 145.0, 148.0, 122.0, 158.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.1}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 159.0, 159.0, 160.0, 161.0, 158.0, 161.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 159.9}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 72.0, 159.0, 70.0, 71.0, 72.0, 71.0, 150.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 87.8}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 133.0, 88.0, 88.0, 89.0, 130.0, 88.0, 89.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 102.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 57.0, 62.0, 52.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 54.8}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 66.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.5}, {"lowerIsBetter": true, "replicates": [76.0, 77.0, 77.0, 76.0, 77.0, 78.0, 78.0, 78.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.2}, {"lowerIsBetter": true, "replicates": [109.0, 113.0, 112.0, 112.0, 111.0, 107.0, 116.0, 115.0, 116.0, 121.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.2}, {"lowerIsBetter": true, "replicates": [135.0, 136.0, 135.0, 145.0, 137.0, 135.0, 135.0, 136.0, 136.0, 132.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.2}, {"lowerIsBetter": true, "replicates": [60.0, 60.0, 61.0, 60.0, 62.0, 60.0, 61.0, 60.0, 60.0, 60.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 60.4}], "name": "kraken", "value": 1454.5000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.05233980107616], "unit": "score", "name": "Richards", "value": 216.50716599999996}, {"lowerIsBetter": false, "replicates": [171.76228100309172], "unit": "score", "name": "DeltaBlue", "value": 384.9389960000001}, {"lowerIsBetter": false, "replicates": [280.1120448179272], "unit": "score", "name": "Encrypt", "value": 950.2661699999999}, {"lowerIsBetter": false, "replicates": [5005.0], "unit": "score", "name": "Decrypt", "value": 53.18301698301701}, {"lowerIsBetter": false, "replicates": [946.073793755913], "unit": "score", "name": "RayTrace", "value": 782.1683729999996}, {"lowerIsBetter": false, "replicates": [557.1030640668523], "unit": "score", "name": "Earley", "value": 1196.3010849999996}, {"lowerIsBetter": false, "replicates": [10547.368421052632], "unit": "score", "name": "Boyer", "value": 63.187609780439146}, {"lowerIsBetter": false, "replicates": [23857.14285714286], "unit": "score", "name": "RegExp", "value": 38.185}, {"lowerIsBetter": false, "replicates": [523.4864300626305], "unit": "score", "name": "Splay", "value": 155.66974675972088}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23302.313374628044}]} 04:30:57 INFO - RETURN: dromaeo_css: 7364.41 04:30:57 INFO - RETURN: kraken: 1454.50 04:30:57 INFO - RETURN: v8_7: 23302.31 04:30:57 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23302.31"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1454.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7364.41"}}} 04:30:57 INFO - Return code: 0 04:30:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 04:30:57 INFO - # TBPL SUCCESS # 04:30:57 INFO - Running post-action listener: _resource_record_post_action 04:30:57 INFO - Running post-run listener: _resource_record_post_run 04:30:58 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 8192; Write bytes: 370692096; Read time: 12; Write time: 255180 04:30:58 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 60796928; Read time: 0; Write time: 61156 04:30:58 INFO - run-tests - Wall time: 1206s; CPU: 13.0%; Read bytes: 8192; Write bytes: 262983680; Read time: 12; Write time: 166072 04:30:58 INFO - Running post-run listener: _upload_blobber_files 04:30:58 INFO - Blob upload gear active. 04:30:58 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:30:58 INFO - Copying logs to upload dir... 04:30:58 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1270.183630 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-12-16 04:30:57.955370) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:30:57.958436) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009630 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-16 04:30:58.010602) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:30:58.010955) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450267724.791344-2076659427 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004659 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-16 04:30:58.061265) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 04:30:58.061590) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-16 04:30:58.061919) ========= ========= Total master_lag: 0.26 =========