builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1450911455.78 results: success (0) buildid: 20151223123430 builduid: 279b3837f4bd4ac6b904048b4e871623 revision: ff3b505eabf0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.776872) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.777570) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.777903) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006205 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.804560) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.805262) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.820352) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.821038) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004612 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.874937) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.875619) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.876523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:35.877191) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-23 14:57:36-- 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% 8.34M=0.001s 2015-12-23 14:57:36 (8.34 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162720 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.055727) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.056500) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014209 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.108271) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.109054) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ff3b505eabf0 --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 ff3b505eabf0 --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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-23 14:57:36,449 Setting DEBUG logging. 2015-12-23 14:57:36,449 attempt 1/10 2015-12-23 14:57:36,449 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ff3b505eabf0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-23 14:57:37,010 unpacking tar archive at: mozilla-beta-ff3b505eabf0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.812021 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.936003) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.936371) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.946054) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.946342) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-23 14:57:36.946794) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2015-12-23 14:57:36.947136) ========= /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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:57:37 INFO - MultiFileLogger online at 20151223 14:57:37 in /builds/slave/test-pgo 14:57:37 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 14:57:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:57:37 INFO - {'append_to_log': False, 14:57:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:57:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:57:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:57:37 INFO - 'branch': 'Mozilla-Beta', 14:57:37 INFO - 'buildbot_json_path': 'buildprops.json', 14:57:37 INFO - 'config_files': ('talos/linux_config.py',), 14:57:37 INFO - 'default_actions': ('clobber', 14:57:37 INFO - 'read-buildbot-config', 14:57:37 INFO - 'download-and-extract', 14:57:37 INFO - 'populate-webroot', 14:57:37 INFO - 'create-virtualenv', 14:57:37 INFO - 'install', 14:57:37 INFO - 'run-tests'), 14:57:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:57:37 INFO - 'download_minidump_stackwalk': True, 14:57:37 INFO - 'download_symbols': 'ondemand', 14:57:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:57:37 INFO - 'tooltool.py': '/tools/tooltool.py', 14:57:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:57:37 INFO - '/tools/misc-python/virtualenv.py')}, 14:57:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:57:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:57:37 INFO - 'installer_path': 'installer.exe', 14:57:37 INFO - 'log_level': 'info', 14:57:37 INFO - 'log_name': 'talos', 14:57:37 INFO - 'log_to_console': True, 14:57:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:57:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:57:37 INFO - 'opt_config_files': (), 14:57:37 INFO - 'pip_index': False, 14:57:37 INFO - 'sps_profile': False, 14:57:37 INFO - 'sps_profile_interval': 0, 14:57:37 INFO - 'suite': 'dromaeojs', 14:57:37 INFO - 'system_bits': '64', 14:57:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:57:37 INFO - 'title': 'talos-linux64-ix-038', 14:57:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:57:37 INFO - 'use_talos_json': True, 14:57:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:57:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:57:37 INFO - 'work_dir': 'build'} 14:57:37 INFO - ##### 14:57:37 INFO - ##### Running clobber step. 14:57:37 INFO - ##### 14:57:37 INFO - Running pre-action listener: _resource_record_pre_action 14:57:37 INFO - Running main action method: clobber 14:57:37 INFO - rmtree: /builds/slave/test-pgo/build 14:57:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:57:38 INFO - Running post-action listener: _resource_record_post_action 14:57:38 INFO - ##### 14:57:38 INFO - ##### Running read-buildbot-config step. 14:57:38 INFO - ##### 14:57:38 INFO - Running pre-action listener: _resource_record_pre_action 14:57:38 INFO - Running main action method: read_buildbot_config 14:57:38 INFO - Using buildbot properties: 14:57:38 INFO - { 14:57:38 INFO - "properties": { 14:57:38 INFO - "buildnumber": 46, 14:57:38 INFO - "product": "firefox", 14:57:38 INFO - "script_repo_revision": "production", 14:57:38 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:57:38 INFO - "repository": "", 14:57:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:57:38 INFO - "buildid": "20151223123430", 14:57:38 INFO - "slavename": "talos-linux64-ix-038", 14:57:38 INFO - "pgo_build": "True", 14:57:38 INFO - "basedir": "/builds/slave/test-pgo", 14:57:38 INFO - "project": "", 14:57:38 INFO - "platform": "ubuntu64_hw", 14:57:38 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:57:38 INFO - "slavebuilddir": "test", 14:57:38 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:57:38 INFO - "branch": "mozilla-beta", 14:57:38 INFO - "repo_path": "releases/mozilla-beta", 14:57:38 INFO - "stage_platform": "linux64-pgo", 14:57:38 INFO - "builduid": "279b3837f4bd4ac6b904048b4e871623", 14:57:38 INFO - "revision": "ff3b505eabf0" 14:57:38 INFO - }, 14:57:38 INFO - "sourcestamp": { 14:57:38 INFO - "repository": "", 14:57:38 INFO - "hasPatch": false, 14:57:38 INFO - "project": "", 14:57:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:57:38 INFO - "changes": [ 14:57:38 INFO - { 14:57:38 INFO - "category": null, 14:57:38 INFO - "files": [ 14:57:38 INFO - { 14:57:38 INFO - "url": null, 14:57:38 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.tar.bz2" 14:57:38 INFO - } 14:57:38 INFO - ], 14:57:38 INFO - "repository": "", 14:57:38 INFO - "rev": "ff3b505eabf0", 14:57:38 INFO - "who": "sendchange", 14:57:38 INFO - "when": 1450911417, 14:57:38 INFO - "number": 7391661, 14:57:38 INFO - "comments": "Bug 1234170 - WebSocket should check if the channel has been opened before send the _close_ notification to the WebSocketEventService, r=jduell a=ritu", 14:57:38 INFO - "project": "", 14:57:38 INFO - "at": "Wed 23 Dec 2015 14:56:57", 14:57:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:57:38 INFO - "revlink": "", 14:57:38 INFO - "properties": [ 14:57:38 INFO - [ 14:57:38 INFO - "buildid", 14:57:38 INFO - "20151223123430", 14:57:38 INFO - "Change" 14:57:38 INFO - ], 14:57:38 INFO - [ 14:57:38 INFO - "builduid", 14:57:38 INFO - "279b3837f4bd4ac6b904048b4e871623", 14:57:38 INFO - "Change" 14:57:38 INFO - ], 14:57:38 INFO - [ 14:57:38 INFO - "pgo_build", 14:57:38 INFO - "True", 14:57:38 INFO - "Change" 14:57:38 INFO - ] 14:57:38 INFO - ], 14:57:38 INFO - "revision": "ff3b505eabf0" 14:57:38 INFO - } 14:57:38 INFO - ], 14:57:38 INFO - "revision": "ff3b505eabf0" 14:57:38 INFO - } 14:57:38 INFO - } 14:57:38 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.tar.bz2. 14:57:38 INFO - Running post-action listener: _resource_record_post_action 14:57:38 INFO - ##### 14:57:38 INFO - ##### Running download-and-extract step. 14:57:38 INFO - ##### 14:57:38 INFO - Running pre-action listener: _resource_record_pre_action 14:57:38 INFO - Running main action method: download_and_extract 14:57:38 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/test_packages.json 14:57:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:57:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:57:38 INFO - Downloaded 1270 bytes. 14:57:38 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:57:38 INFO - Using the following test package requirements: 14:57:38 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:57:38 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:57:38 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:57:38 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:57:38 INFO - u'jsshell-linux-x86_64.zip'], 14:57:38 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:57:38 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:57:38 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:57:38 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:57:38 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 14:57:38 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:57:38 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 14:57:38 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:57:38 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:57:38 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:57:38 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:57:38 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:57:38 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:57:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:39 INFO - Downloaded 22571841 bytes. 14:57:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:57:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:57:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:57:40 INFO - Return code: 0 14:57:40 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 14:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:57:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:40 INFO - Downloaded 22571841 bytes. 14:57:40 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 14:57:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:57:40 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 14:57:42 INFO - Return code: 0 14:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:57:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:44 INFO - Downloaded 11131970 bytes. 14:57:44 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 14:57:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:57:44 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 14:57:44 INFO - Return code: 0 14:57:44 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')]} 14:57:44 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:57:44 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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 14:57:47 INFO - Downloaded 56619012 bytes. 14:57:47 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:57:47 INFO - mkdir: /builds/slave/test-pgo/properties 14:57:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:57:47 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:57:47 INFO - Contents: 14:57:47 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:57:47 INFO - Running post-action listener: _resource_record_post_action 14:57:47 INFO - Running post-action listener: set_extra_try_arguments 14:57:47 INFO - ##### 14:57:47 INFO - ##### Running populate-webroot step. 14:57:47 INFO - ##### 14:57:47 INFO - Running pre-action listener: _resource_record_pre_action 14:57:47 INFO - Running main action method: populate_webroot 14:57:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:57:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:57:47 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 14:57:47 INFO - '../fennec_ids.txt'], 14:57:47 INFO - 'tests': ['tcheck2']}, 14:57:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:57:47 INFO - '--tppagecycles', 14:57:47 INFO - '7'], 14:57:47 INFO - 'tests': ['tsvgm']}}, 14:57:47 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:57:47 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:57:47 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:57:47 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:57:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:57:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:57:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'tests': ['damp', 'tps']}, 14:57:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['damp', 'tps']}, 14:57:47 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:57:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:57:47 INFO - 'other': {'tests': ['a11yr', 14:57:47 INFO - 'ts_paint', 14:57:47 INFO - 'tpaint', 14:57:47 INFO - 'sessionrestore', 14:57:47 INFO - 'sessionrestore_no_auto_restore']}, 14:57:47 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['a11yr', 14:57:47 INFO - 'ts_paint', 14:57:47 INFO - 'tpaint', 14:57:47 INFO - 'sessionrestore', 14:57:47 INFO - 'sessionrestore_no_auto_restore']}, 14:57:47 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['a11yr', 14:57:47 INFO - 'ts_paint', 14:57:47 INFO - 'tpaint', 14:57:47 INFO - 'sessionrestore', 14:57:47 INFO - 'sessionrestore_no_auto_restore']}, 14:57:47 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['a11yr', 14:57:47 INFO - 'ts_paint', 14:57:47 INFO - 'tpaint', 14:57:47 INFO - 'sessionrestore', 14:57:47 INFO - 'sessionrestore_no_auto_restore']}, 14:57:47 INFO - 'other_l64': {'tests': ['a11yr', 14:57:47 INFO - 'ts_paint', 14:57:47 INFO - 'tpaint', 14:57:47 INFO - 'sessionrestore', 14:57:47 INFO - 'sessionrestore_no_auto_restore']}, 14:57:47 INFO - 'other_nol64': {'tests': ['a11yr', 14:57:47 INFO - 'ts_paint', 14:57:47 INFO - 'tpaint', 14:57:47 INFO - 'sessionrestore', 14:57:47 INFO - 'sessionrestore_no_auto_restore']}, 14:57:47 INFO - 'svgr': {'tests': ['tsvgx', 14:57:47 INFO - 'tsvgr_opacity', 14:57:47 INFO - 'tart', 14:57:47 INFO - 'tscrollx', 14:57:47 INFO - 'cart']}, 14:57:47 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['tsvgx', 14:57:47 INFO - 'tsvgr_opacity', 14:57:47 INFO - 'tart', 14:57:47 INFO - 'tscrollx', 14:57:47 INFO - 'cart']}, 14:57:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'tests': ['tp5o']}, 14:57:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'talos_options': ['--e10s'], 14:57:47 INFO - 'tests': ['tp5o']}, 14:57:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'talos_options': ['--xperf_path', 14:57:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:57:47 INFO - 'tests': ['tp5n']}, 14:57:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:57:47 INFO - 'talos_options': ['--e10s', 14:57:47 INFO - '--xperf_path', 14:57:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:57:47 INFO - 'tests': ['tp5n']}}, 14:57:47 INFO - 'talos.zip': {'path': '', 14:57:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:57:47 INFO - Running post-action listener: _resource_record_post_action 14:57:47 INFO - ##### 14:57:47 INFO - ##### Running create-virtualenv step. 14:57:47 INFO - ##### 14:57:47 INFO - Running pre-action listener: _resource_record_pre_action 14:57:47 INFO - Running main action method: create_virtualenv 14:57:47 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:57:47 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 14:57:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:57:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:57:48 INFO - Using real prefix '/usr' 14:57:48 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:57:48 INFO - Installing distribute.............................................................................................................................................................................................done. 14:57:50 INFO - Installing pip.................done. 14:57:50 INFO - Return code: 0 14:57:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:57:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 14:57:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:57:50 INFO - Using env: {'DISPLAY': ':0', 14:57:50 INFO - 'HOME': '/home/cltbld', 14:57:50 INFO - 'LANG': 'en_US.UTF-8', 14:57:50 INFO - 'LANGUAGE': 'en_US:en', 14:57:50 INFO - 'LOGNAME': 'cltbld', 14:57:50 INFO - 'MAIL': '/var/mail/cltbld', 14:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:50 INFO - 'MOZ_NO_REMOTE': '1', 14:57:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:57:50 INFO - 'NO_EM_RESTART': '1', 14:57:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:57:50 INFO - 'PWD': '/builds/slave/test-pgo', 14:57:50 INFO - 'SHELL': '/bin/bash', 14:57:50 INFO - 'SHLVL': '1', 14:57:50 INFO - 'TERM': 'linux', 14:57:50 INFO - 'TMOUT': '86400', 14:57:50 INFO - 'USER': 'cltbld', 14:57:50 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:50 INFO - '_': '/tools/buildbot/bin/python'} 14:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:50 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 14:57:50 INFO - Cleaning up... 14:57:50 INFO - Return code: 0 14:57:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:57:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 14:57:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:57:50 INFO - Using env: {'DISPLAY': ':0', 14:57:50 INFO - 'HOME': '/home/cltbld', 14:57:50 INFO - 'LANG': 'en_US.UTF-8', 14:57:50 INFO - 'LANGUAGE': 'en_US:en', 14:57:50 INFO - 'LOGNAME': 'cltbld', 14:57:50 INFO - 'MAIL': '/var/mail/cltbld', 14:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:50 INFO - 'MOZ_NO_REMOTE': '1', 14:57:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:57:50 INFO - 'NO_EM_RESTART': '1', 14:57:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:57:50 INFO - 'PWD': '/builds/slave/test-pgo', 14:57:50 INFO - 'SHELL': '/bin/bash', 14:57:50 INFO - 'SHLVL': '1', 14:57:50 INFO - 'TERM': 'linux', 14:57:50 INFO - 'TMOUT': '86400', 14:57:50 INFO - 'USER': 'cltbld', 14:57:50 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:50 INFO - '_': '/tools/buildbot/bin/python'} 14:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:50 INFO - Downloading/unpacking psutil>=0.7.1 14:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:52 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:57:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:57:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:57:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:57:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:57:52 INFO - Installing collected packages: psutil 14:57:52 INFO - Running setup.py install for psutil 14:57:52 INFO - building 'psutil._psutil_linux' extension 14:57:52 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 14:57:53 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 14:57:53 INFO - building 'psutil._psutil_posix' extension 14:57:53 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 14:57:53 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 14:57:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:57:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:57:53 INFO - Successfully installed psutil 14:57:53 INFO - Cleaning up... 14:57:53 INFO - Return code: 0 14:57:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:57:53 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')]} 14:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:53 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')]} 14:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:53 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 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:57:53 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 14:57:53 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 14:57:53 INFO - Using env: {'DISPLAY': ':0', 14:57:53 INFO - 'HOME': '/home/cltbld', 14:57:53 INFO - 'LANG': 'en_US.UTF-8', 14:57:53 INFO - 'LANGUAGE': 'en_US:en', 14:57:53 INFO - 'LOGNAME': 'cltbld', 14:57:53 INFO - 'MAIL': '/var/mail/cltbld', 14:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:53 INFO - 'MOZ_NO_REMOTE': '1', 14:57:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:57:53 INFO - 'NO_EM_RESTART': '1', 14:57:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:57:53 INFO - 'PWD': '/builds/slave/test-pgo', 14:57:53 INFO - 'SHELL': '/bin/bash', 14:57:53 INFO - 'SHLVL': '1', 14:57:53 INFO - 'TERM': 'linux', 14:57:53 INFO - 'TMOUT': '86400', 14:57:53 INFO - 'USER': 'cltbld', 14:57:53 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:53 INFO - '_': '/tools/buildbot/bin/python'} 14:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:57:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:57:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:57:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:57:55 INFO - Installing collected packages: mozsystemmonitor 14:57:55 INFO - Running setup.py install for mozsystemmonitor 14:57:55 INFO - Successfully installed mozsystemmonitor 14:57:55 INFO - Cleaning up... 14:57:55 INFO - Return code: 0 14:57:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:55 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 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:57:55 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 14:57:55 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 14:57:55 INFO - Using env: {'DISPLAY': ':0', 14:57:55 INFO - 'HOME': '/home/cltbld', 14:57:55 INFO - 'LANG': 'en_US.UTF-8', 14:57:55 INFO - 'LANGUAGE': 'en_US:en', 14:57:55 INFO - 'LOGNAME': 'cltbld', 14:57:55 INFO - 'MAIL': '/var/mail/cltbld', 14:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:55 INFO - 'MOZ_NO_REMOTE': '1', 14:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:57:55 INFO - 'NO_EM_RESTART': '1', 14:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:57:55 INFO - 'PWD': '/builds/slave/test-pgo', 14:57:55 INFO - 'SHELL': '/bin/bash', 14:57:55 INFO - 'SHLVL': '1', 14:57:55 INFO - 'TERM': 'linux', 14:57:55 INFO - 'TMOUT': '86400', 14:57:55 INFO - 'USER': 'cltbld', 14:57:55 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:55 INFO - '_': '/tools/buildbot/bin/python'} 14:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:55 INFO - Downloading/unpacking blobuploader==1.2.4 14:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:57 INFO - Downloading blobuploader-1.2.4.tar.gz 14:57:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:57:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:57:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:57:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:57:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:57 INFO - Downloading docopt-0.6.1.tar.gz 14:57:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:57:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:57:58 INFO - Installing collected packages: blobuploader, requests, docopt 14:57:58 INFO - Running setup.py install for blobuploader 14:57:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:57:58 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:57:58 INFO - Running setup.py install for requests 14:57:58 INFO - Running setup.py install for docopt 14:57:58 INFO - Successfully installed blobuploader requests docopt 14:57:58 INFO - Cleaning up... 14:57:58 INFO - Return code: 0 14:57:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:57:58 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')]} 14:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:58 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')]} 14:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:58 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 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:57:58 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 14:57:58 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 14:57:58 INFO - Using env: {'DISPLAY': ':0', 14:57:58 INFO - 'HOME': '/home/cltbld', 14:57:58 INFO - 'LANG': 'en_US.UTF-8', 14:57:58 INFO - 'LANGUAGE': 'en_US:en', 14:57:58 INFO - 'LOGNAME': 'cltbld', 14:57:58 INFO - 'MAIL': '/var/mail/cltbld', 14:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:58 INFO - 'MOZ_NO_REMOTE': '1', 14:57:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:57:58 INFO - 'NO_EM_RESTART': '1', 14:57:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:57:58 INFO - 'PWD': '/builds/slave/test-pgo', 14:57:58 INFO - 'SHELL': '/bin/bash', 14:57:58 INFO - 'SHLVL': '1', 14:57:58 INFO - 'TERM': 'linux', 14:57:58 INFO - 'TMOUT': '86400', 14:57:58 INFO - 'USER': 'cltbld', 14:57:58 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:58 INFO - '_': '/tools/buildbot/bin/python'} 14:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:57:58 INFO - Running setup.py (path:/tmp/pip-KCO6hC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:57:58 INFO - Running setup.py (path:/tmp/pip-w9o8YE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:57:58 INFO - Running setup.py (path:/tmp/pip-IusKME-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:57:59 INFO - Running setup.py (path:/tmp/pip-RmX1fh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:57:59 INFO - Running setup.py (path:/tmp/pip-BJSk7g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:57:59 INFO - Running setup.py (path:/tmp/pip-4LAn5u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:57:59 INFO - Running setup.py (path:/tmp/pip-j5eJBO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:57:59 INFO - Running setup.py (path:/tmp/pip-vn6vrE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:57:59 INFO - Running setup.py (path:/tmp/pip-FQbRMo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:57:59 INFO - Running setup.py (path:/tmp/pip-OkdEam-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:57:59 INFO - Running setup.py (path:/tmp/pip-OfKIMS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:57:59 INFO - Running setup.py (path:/tmp/pip-JOQ1z7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:57:59 INFO - Running setup.py (path:/tmp/pip-24W5Ef-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:57:59 INFO - Running setup.py (path:/tmp/pip-atu6Uc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:57:59 INFO - Running setup.py (path:/tmp/pip-_FjqDl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:57:59 INFO - Running setup.py (path:/tmp/pip-oNHydg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:57:59 INFO - Running setup.py (path:/tmp/pip-NFSaPZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:58:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:58:00 INFO - Running setup.py install for manifestparser 14:58:00 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Running setup.py install for mozcrash 14:58:00 INFO - Running setup.py install for mozdebug 14:58:00 INFO - Running setup.py install for mozdevice 14:58:00 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Running setup.py install for mozfile 14:58:00 INFO - Running setup.py install for mozhttpd 14:58:00 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Running setup.py install for mozinfo 14:58:00 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Running setup.py install for mozInstall 14:58:00 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:58:00 INFO - Running setup.py install for mozleak 14:58:00 INFO - Running setup.py install for mozlog 14:58:01 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:58:01 INFO - Running setup.py install for moznetwork 14:58:01 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:58:01 INFO - Running setup.py install for mozprocess 14:58:01 INFO - Running setup.py install for mozprofile 14:58:01 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:58:01 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:58:01 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:58:01 INFO - Running setup.py install for mozrunner 14:58:01 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:58:01 INFO - Running setup.py install for mozscreenshot 14:58:01 INFO - Running setup.py install for moztest 14:58:01 INFO - Running setup.py install for mozversion 14:58:01 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:58:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:58:01 INFO - Cleaning up... 14:58:01 INFO - Return code: 0 14:58:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:58: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')]} 14:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:01 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 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:58:01 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 14:58:01 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 14:58:01 INFO - Using env: {'DISPLAY': ':0', 14:58:01 INFO - 'HOME': '/home/cltbld', 14:58:01 INFO - 'LANG': 'en_US.UTF-8', 14:58:01 INFO - 'LANGUAGE': 'en_US:en', 14:58:01 INFO - 'LOGNAME': 'cltbld', 14:58:01 INFO - 'MAIL': '/var/mail/cltbld', 14:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:01 INFO - 'MOZ_NO_REMOTE': '1', 14:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:01 INFO - 'NO_EM_RESTART': '1', 14:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:58:01 INFO - 'PWD': '/builds/slave/test-pgo', 14:58:01 INFO - 'SHELL': '/bin/bash', 14:58:01 INFO - 'SHLVL': '1', 14:58:01 INFO - 'TERM': 'linux', 14:58:01 INFO - 'TMOUT': '86400', 14:58:01 INFO - 'USER': 'cltbld', 14:58:01 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:01 INFO - '_': '/tools/buildbot/bin/python'} 14:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:58:02 INFO - Running setup.py (path:/tmp/pip-mwEYjZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:58:02 INFO - Running setup.py (path:/tmp/pip-dw29Ac-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:58:02 INFO - Running setup.py (path:/tmp/pip-Bfs4qR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:58:02 INFO - Running setup.py (path:/tmp/pip-b31VuX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:58:02 INFO - Running setup.py (path:/tmp/pip-XkEjmG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:58:02 INFO - Running setup.py (path:/tmp/pip-5PCXHn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:58:02 INFO - Running setup.py (path:/tmp/pip-CwG9fb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:58:02 INFO - Running setup.py (path:/tmp/pip-pcxlLw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:58:02 INFO - Running setup.py (path:/tmp/pip-jpYpdM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:58:02 INFO - Running setup.py (path:/tmp/pip-pqk5rF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:58:02 INFO - Running setup.py (path:/tmp/pip-sSYBoC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:58:02 INFO - Running setup.py (path:/tmp/pip-JtrnKy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:58:02 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)) 14:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:58:02 INFO - Running setup.py (path:/tmp/pip-YbePeH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:58:03 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)) 14:58:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:58:03 INFO - Running setup.py (path:/tmp/pip-JNxDEP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:58:03 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)) 14:58:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:58:03 INFO - Running setup.py (path:/tmp/pip-0lNAmh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:58:03 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)) 14:58:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:58:03 INFO - Running setup.py (path:/tmp/pip-u9Vil0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:58:03 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)) 14:58:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:58:03 INFO - Running setup.py (path:/tmp/pip-vrs2xs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:58:03 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)) 14:58:03 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)) 14:58:03 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)) 14:58:03 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)) 14:58:03 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)) 14:58:03 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)) 14:58:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:04 INFO - Downloading blessings-1.5.1.tar.gz 14:58:04 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 14:58:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:58:05 INFO - Installing collected packages: blessings 14:58:05 INFO - Running setup.py install for blessings 14:58:05 INFO - Successfully installed blessings 14:58:05 INFO - Cleaning up... 14:58:05 INFO - Return code: 0 14:58:05 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:58:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:58:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:58:05 INFO - Reading from file tmpfile_stdout 14:58:05 INFO - Current package versions: 14:58:05 INFO - argparse == 1.2.1 14:58:05 INFO - blessings == 1.5.1 14:58:05 INFO - blobuploader == 1.2.4 14:58:05 INFO - docopt == 0.6.1 14:58:05 INFO - manifestparser == 1.1 14:58:05 INFO - mozInstall == 1.12 14:58:05 INFO - mozcrash == 0.16 14:58:05 INFO - mozdebug == 0.1 14:58:05 INFO - mozdevice == 0.46 14:58:05 INFO - mozfile == 1.2 14:58:05 INFO - mozhttpd == 0.7 14:58:05 INFO - mozinfo == 0.8 14:58:05 INFO - mozleak == 0.1 14:58:05 INFO - mozlog == 3.0 14:58:05 INFO - moznetwork == 0.27 14:58:05 INFO - mozprocess == 0.22 14:58:05 INFO - mozprofile == 0.27 14:58:05 INFO - mozrunner == 6.11 14:58:05 INFO - mozscreenshot == 0.1 14:58:05 INFO - mozsystemmonitor == 0.0 14:58:05 INFO - moztest == 0.7 14:58:05 INFO - mozversion == 1.4 14:58:05 INFO - psutil == 3.1.1 14:58:05 INFO - requests == 1.2.3 14:58:05 INFO - wsgiref == 0.1.2 14:58:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:58: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')]} 14:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:58:05 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 14:58:05 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 14:58:05 INFO - Using env: {'DISPLAY': ':0', 14:58:05 INFO - 'HOME': '/home/cltbld', 14:58:05 INFO - 'LANG': 'en_US.UTF-8', 14:58:05 INFO - 'LANGUAGE': 'en_US:en', 14:58:05 INFO - 'LOGNAME': 'cltbld', 14:58:05 INFO - 'MAIL': '/var/mail/cltbld', 14:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:05 INFO - 'MOZ_NO_REMOTE': '1', 14:58:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:05 INFO - 'NO_EM_RESTART': '1', 14:58:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:58:05 INFO - 'PWD': '/builds/slave/test-pgo', 14:58:05 INFO - 'SHELL': '/bin/bash', 14:58:05 INFO - 'SHLVL': '1', 14:58:05 INFO - 'TERM': 'linux', 14:58:05 INFO - 'TMOUT': '86400', 14:58:05 INFO - 'USER': 'cltbld', 14:58:05 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:05 INFO - '_': '/tools/buildbot/bin/python'} 14:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 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)) 14:58:05 INFO - Cleaning up... 14:58:05 INFO - Return code: 0 14:58:05 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:58: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')]} 14:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:58:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 14:58:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:58:05 INFO - Using env: {'DISPLAY': ':0', 14:58:05 INFO - 'HOME': '/home/cltbld', 14:58:05 INFO - 'LANG': 'en_US.UTF-8', 14:58:05 INFO - 'LANGUAGE': 'en_US:en', 14:58:05 INFO - 'LOGNAME': 'cltbld', 14:58:05 INFO - 'MAIL': '/var/mail/cltbld', 14:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:05 INFO - 'MOZ_NO_REMOTE': '1', 14:58:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:05 INFO - 'NO_EM_RESTART': '1', 14:58:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:58:05 INFO - 'PWD': '/builds/slave/test-pgo', 14:58:05 INFO - 'SHELL': '/bin/bash', 14:58:05 INFO - 'SHLVL': '1', 14:58:05 INFO - 'TERM': 'linux', 14:58:05 INFO - 'TMOUT': '86400', 14:58:05 INFO - 'USER': 'cltbld', 14:58:05 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:05 INFO - '_': '/tools/buildbot/bin/python'} 14:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:05 INFO - Downloading/unpacking jsonschema 14:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:58:07 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 14:58:07 INFO - Downloading/unpacking functools32 (from jsonschema) 14:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:07 INFO - Downloading functools32-3.2.3-2.tar.gz 14:58:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:58:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:58:07 INFO - warning: no files found matching '*.txt' 14:58:07 INFO - no previously-included directories found matching 'build' 14:58:07 INFO - no previously-included directories found matching 'dist' 14:58:07 INFO - no previously-included directories found matching '.git*' 14:58:07 INFO - Installing collected packages: jsonschema, functools32 14:58:07 INFO - Running setup.py install for functools32 14:58:07 INFO - warning: no files found matching '*.txt' 14:58:07 INFO - no previously-included directories found matching 'build' 14:58:07 INFO - no previously-included directories found matching 'dist' 14:58:07 INFO - no previously-included directories found matching '.git*' 14:58:07 INFO - Successfully installed jsonschema functools32 14:58:07 INFO - Cleaning up... 14:58:08 INFO - Return code: 0 14:58:08 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:58:08 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')]} 14:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:08 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')]} 14:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:08 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 0x7fa29371ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf3e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', '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 14:58:08 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 14:58:08 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 14:58:08 INFO - Using env: {'DISPLAY': ':0', 14:58:08 INFO - 'HOME': '/home/cltbld', 14:58:08 INFO - 'LANG': 'en_US.UTF-8', 14:58:08 INFO - 'LANGUAGE': 'en_US:en', 14:58:08 INFO - 'LOGNAME': 'cltbld', 14:58:08 INFO - 'MAIL': '/var/mail/cltbld', 14:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:08 INFO - 'MOZ_NO_REMOTE': '1', 14:58:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:08 INFO - 'NO_EM_RESTART': '1', 14:58:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:58:08 INFO - 'PWD': '/builds/slave/test-pgo', 14:58:08 INFO - 'SHELL': '/bin/bash', 14:58:08 INFO - 'SHLVL': '1', 14:58:08 INFO - 'TERM': 'linux', 14:58:08 INFO - 'TMOUT': '86400', 14:58:08 INFO - 'USER': 'cltbld', 14:58:08 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:08 INFO - '_': '/tools/buildbot/bin/python'} 14:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:08 INFO - Downloading/unpacking flake8 14:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:58:09 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 14:58:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:58:10 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 14:58:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 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 14:58:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:58:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:58:10 INFO - no previously-included directories found matching 'docs/_build' 14:58:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:58:10 INFO - Running setup.py install for pep8 14:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:58:10 INFO - no previously-included directories found matching 'docs/_build' 14:58:10 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:58:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:58:10 INFO - Cleaning up... 14:58:10 INFO - Return code: 0 14:58:10 INFO - Running post-action listener: _resource_record_post_action 14:58:10 INFO - Running post-action listener: _start_resource_monitoring 14:58:10 INFO - Starting resource monitoring. 14:58:10 INFO - ##### 14:58:10 INFO - ##### Running install step. 14:58:10 INFO - ##### 14:58:10 INFO - Running pre-action listener: _resource_record_pre_action 14:58:10 INFO - Running main action method: install 14:58:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:58:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:58:11 INFO - Reading from file tmpfile_stdout 14:58:11 INFO - Detecting whether we're running mozinstall >=1.0... 14:58:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:58:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:58:11 INFO - Reading from file tmpfile_stdout 14:58:11 INFO - Output received: 14:58:11 INFO - Usage: mozinstall [options] installer 14:58:11 INFO - Options: 14:58:11 INFO - -h, --help show this help message and exit 14:58:11 INFO - -d DEST, --destination=DEST 14:58:11 INFO - Directory to install application into. [default: 14:58:11 INFO - "/builds/slave/test-pgo"] 14:58:11 INFO - --app=APP Application being installed. [default: firefox] 14:58:11 INFO - mkdir: /builds/slave/test-pgo/build/application 14:58:11 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'] 14:58:11 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 14:58:26 INFO - Reading from file tmpfile_stdout 14:58:26 INFO - Output received: 14:58:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:58:26 INFO - Running post-action listener: _resource_record_post_action 14:58:26 INFO - ##### 14:58:26 INFO - ##### Running run-tests step. 14:58:26 INFO - ##### 14:58:26 INFO - Running pre-action listener: _resource_record_pre_action 14:58:26 INFO - Running main action method: run_tests 14:58:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:58:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:58:26 INFO - Python 2.7.3 14:58:26 INFO - Return code: 0 14:58:26 INFO - grabbing minidump binary from tooltool 14:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:26 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 0xfcd350>, '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 0xfce250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfce6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:58:26 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 14:58:26 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 14:58:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:58:26 INFO - Return code: 0 14:58:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:58:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:58:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:58:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:58:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:58:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:58:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:58:27 INFO - Return code: 0 14:58:27 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/1450902870/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:58:27 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/1450902870/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost 14:58:27 INFO - Using env: {'DISPLAY': ':0', 14:58:27 INFO - 'HOME': '/home/cltbld', 14:58:27 INFO - 'LANG': 'en_US.UTF-8', 14:58:27 INFO - 'LANGUAGE': 'en_US:en', 14:58:27 INFO - 'LOGNAME': 'cltbld', 14:58:27 INFO - 'MAIL': '/var/mail/cltbld', 14:58:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:58:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:27 INFO - 'MOZ_NO_REMOTE': '1', 14:58:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:58:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:27 INFO - 'NO_EM_RESTART': '1', 14:58:27 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:58:27 INFO - 'PWD': '/builds/slave/test-pgo', 14:58:27 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:58:27 INFO - 'SHELL': '/bin/bash', 14:58:27 INFO - 'SHLVL': '1', 14:58:27 INFO - 'TERM': 'linux', 14:58:27 INFO - 'TMOUT': '86400', 14:58:27 INFO - 'USER': 'cltbld', 14:58:27 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354', 14:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:27 INFO - '_': '/tools/buildbot/bin/python'} 14:58:27 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/1450902870/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost'] with output_timeout 3600 14:58:27 INFO - mozversion INFO | application_buildid: 20151223123430 14:58:27 INFO - mozversion INFO | application_changeset: ff3b505eabf0c896ef94cccf1101a79bc43cf28b 14:58:27 INFO - mozversion INFO | application_display_name: Nightly 14:58:27 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:58:27 INFO - mozversion INFO | application_name: Firefox 14:58:27 INFO - mozversion INFO | application_remotingname: firefox 14:58:27 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:58:27 INFO - mozversion INFO | application_vendor: Mozilla 14:58:27 INFO - mozversion INFO | application_version: 44.0 14:58:27 INFO - mozversion INFO | platform_buildid: 20151223123430 14:58:27 INFO - mozversion INFO | platform_changeset: ff3b505eabf0c896ef94cccf1101a79bc43cf28b 14:58:27 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:58:27 INFO - mozversion INFO | platform_version: 44.0 14:58:27 INFO - 2015-12-23 14:58:27,840 DEBUG : using testdate: 1450911507 14:58:27 INFO - 2015-12-23 14:58:27,840 DEBUG : actual date: 1450911507 14:58:27 INFO - 2015-12-23 14:58:27,849 INFO : starting webserver on 'localhost:45873' 14:58:27 INFO - 2015-12-23 14:58:27,850 INFO : Starting test suite talos-linux64-ix-038 14:58:27 INFO - 2015-12-23 14:58:27,851 INFO : Starting test dromaeo_css 14:58:27 INFO - 2015-12-23 14:58:27,851 DEBUG : operating with platform_type : linux_ 14:58:27 INFO - 2015-12-23 14:58:27,852 INFO : Initialising browser for dromaeo_css test... 14:58:27 INFO - 2015-12-23 14:58:27,868 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRd7w0X/profile http://localhost:45873/getInfo.html 14:58:31 INFO - 2015-12-23 14:58:31,976 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:58:31 INFO - 2015-12-23 14:58:31,976 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:58:31 INFO - 2015-12-23 14:58:31,976 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:58:31 INFO - 2015-12-23 14:58:31,976 DEBUG : BROWSER_OUTPUT: __metrics 14:58:32 INFO - 2015-12-23 14:58:32,078 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] 14:58:33 INFO - 2015-12-23 14:58:33,630 INFO : Browser initialized. 14:58:33 INFO - 2015-12-23 14:58:33,631 INFO : Running cycle 1/1 for dromaeo_css test... 14:58:33 INFO - 2015-12-23 14:58:33,631 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRd7w0X/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:58:45 INFO - 2015-12-23 14:58:45,126 DEBUG : BROWSER_OUTPUT: RSS: Main: 128319488 14:58:45 INFO - 2015-12-23 14:58:45,127 DEBUG : BROWSER_OUTPUT: 14:59:35 INFO - 2015-12-23 14:59:35,997 DEBUG : BROWSER_OUTPUT: 1450911575993 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] 15:01:44 INFO - 2015-12-23 15:01:44,289 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45873/tests/dromaeo/cssquery-ext.html) 15:01:45 INFO - 2015-12-23 15:01:45,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 242794496 15:01:45 INFO - 2015-12-23 15:01:45,386 DEBUG : BROWSER_OUTPUT: 15:04:47 INFO - 2015-12-23 15:04:47,488 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/dromaeo/cssquery-ext.html (next: http://localhost:45873/tests/dromaeo/cssquery-jquery.html) 15:04:48 INFO - 2015-12-23 15:04:48,051 DEBUG : BROWSER_OUTPUT: RSS: Main: 214044672 15:04:48 INFO - 2015-12-23 15:04:48,051 DEBUG : BROWSER_OUTPUT: 15:07:49 INFO - 2015-12-23 15:07:49,724 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45873/tests/dromaeo/cssquery-mootools.html) 15:07:50 INFO - 2015-12-23 15:07:50,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 206888960 15:07:50 INFO - 2015-12-23 15:07:50,285 DEBUG : BROWSER_OUTPUT: 15:10:52 INFO - 2015-12-23 15:10:52,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45873/tests/dromaeo/cssquery-prototype.html) 15:10:52 INFO - 2015-12-23 15:10:52,581 DEBUG : BROWSER_OUTPUT: RSS: Main: 194691072 15:10:52 INFO - 2015-12-23 15:10:52,581 DEBUG : BROWSER_OUTPUT: 15:13:54 INFO - 2015-12-23 15:13:54,885 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45873/tests/dromaeo/cssquery-yui.html) 15:13:55 INFO - 2015-12-23 15:13:55,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 196390912 15:13:55 INFO - 2015-12-23 15:13:55,471 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,577 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/dromaeo/cssquery-yui.html (next: http://localhost:45873/tests/dromaeo/cssquery-dojo.html) 15:16:57 INFO - 2015-12-23 15:16:57,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 233816064 15:16:57 INFO - 2015-12-23 15:16:57,836 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,836 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:16:57 INFO - 2015-12-23 15:16:57,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:16:57 INFO - 2015-12-23 15:16:57,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:16:57 INFO - 2015-12-23 15:16:57,837 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:16:57 INFO - 2015-12-23 15:16:57,837 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3255.983720081399;3391.9321613567745;3402.6257111717705;3409.267007593365;3426.4175090234658;5631.099024156136;5646.830595082144;5658.971705141479;5661.122526008476;5716.6284191527575;5676.687782171979;5688.345840228375;5696.287964004499;5699.458551437607;5703.942960570393;5036.521530454601;5124.180131179012;5133.3839939207255;5189.403218629849;5229.241759944805;5220.791168353261;5243.318368612094;5260.395054568716;5261.526462618363;5269.8155564555245;5167.560757335612;5186.247994040862;5211.5049070338255;5216.347956505437;5289.656172348804;5184.637075404723;5264.473552644743;5268.236105764643;5274.657147285414;5276.604254680914;5159.071367153911;5194.97402512985;5229.581633469314;5236.423993360727;5238.895222095537;5308.362996440414;5358.678479291255;5413.458654134594;5487.835364939058;5498.780048798043;6118.449339559416;6196.318404975449;6199.845003874894;6208.379162083801;6214.40963108504;6213.409726076015;6225.564210505267;6244.843878903019;6250.749970001195;6251.812445626639;5537.418571050022;5647.915281270784;5667.8583035424035;5742.454466825645;5773.6824474654295;6215.440610345091;6241.209739359428;6264.655443950626;6268.09112678659;6275.811725648238;5943.138244954457;6077;6088.086786981989;6091.48222401092;6096.207493025879;6112;6124.5712800103565;6208.565400421927;6216.44052035319;6217.222847144107;6098.939010609926;6172.506199504029;6174.351693072253;6181.598196117328;6185.628862268189;5915.349311575723;6040.124181993587;6140.570160088841;6164.7842325518395;6178.938210617926;4668.369770080995;4678.2280923647895;4678.2982552616695;4682.601978831769;4685.4143232095985;4109.116539943926;4193.706440549132;4195.727277726999;4204.285271503852;4223.68322375823;5783.826485205451;5807.070868660994;5811.418858114154;5812.534997200214;5814.244148260699;6074.726637301332;6117.357677443893;6154.476869466056;6160.291566469824;6164.630122192593;5623.044082505984;5697.686627235542;5719.971400143056;5729.1119876419225;5778.422157784188;5306.9386122775595;5311.123664595299;5313.681179129265;5314.388845282842;5331.253624492574;6120.741183502672;6216.658083805343;6409.9679501602195;6410.775622853178;6413.871722565523;6036.8188954331445;6081.787137450168;6092;6098.024316109401;6138.969305153446;6280;6503.382178693017;6508.414242718178;6510.44661203803;6511;5929.436703513159;6028.33688294296;6054.455099041107;6187.535934804998;6200.658963757037;6087.421694938974;6102.115193296859;6102.664353460602;6105.084237364254;6114.5414093942245;5956.166136740946;6015.097735339734;6021.307549631673;6021.42796434338;6024.8795024100145;5779.2775903012125;5898.026825573904;5996.340402555628;6041.124037014696;6144.17053697763;5982.6410415373475;5989.700514974146;5991.3409524953095;5997.670128142994;6002.489788367863;6050.183225264533;6061.575689701678;6066.3023752269055;6072.757089716539;6080.179175811209;5642.181883626769;5747.396523364986;5916.940830591811;6002.519798416203;6013.699315034143 15:16:57 INFO - 2015-12-23 15:16:57,837 DEBUG : BROWSER_OUTPUT: |1;ext.html;581.4331027248434;617.8362733875526;620.7020630097553;626.4581137316221;636.6434796513953;43831.561684383145;44994;45073.77463112684;45127.77436112819;45143.322850157274;47519;48147.27779083316;48148.2777758333;48159.27761083587;48186;8242.464239824421;8368.037675667285;9244.907550924505;9465.479398633073;10760.515776790064;10999.065079468255;11089.778204435906;11095.50070246841;11119.833202501968;11124.61063862765;11030.172737044713;11125.833112503318;11142.27575207609;11146.21976461644;11148.108151347913;11070.335779853194;11127.165462590257;11133.332000080021;11135.888641113566;11151.776964460705;10927.071198948099;11023.559057637685;11051.447427628667;11054.668359949215;11059.502322395512;43516;43568.564314356765;43963.78018109889;44029.33955990663;44048.55951440477;3502.141975216088;3573.749837511349;3587.1749497615697;3806.371948628501;3852.97896057545;33818.6618133818;33849.830750846086;33901;34262.82868585666;34751.4787278191;3311.7350611950988;3370.3427831572853;3397.5583174187445;3418.060033490785;3438.48422736589;309832;310075;310153;311085;447536;58489;75390.6230468855;76901.61549192219;77016.61491692506;77049.2295077042;71815.6409217961;71834;72306;72344.63827680828;72392.27607723961;16440;16566.337346506003;16653.16734163311;16674.333026678796;16682.83317166837;12513.311767852674;12652.557160499338;12669.809952850715;12684.365781710878;12694.746105078031;22737.31788046361;23037;23086.769132308462;23126.42183945381;23126.768732312798;8454.915450845412;8621.7274296488;8710.303175745925;8817.911820881838;8857.689980850639;917.4128557723062;975.707287813653;980.5979548385128;1064.499685147979;1186.9169158158845;29925.700742992725;30275;30498.54252186219;30762.53856192159;30764.25611509356;1051.8001579699833;1121.1367247219596;1167.3754541320352;1184.9631572374174;1246.1837496439846;14921.328540215716;15841.68316633684;16920.323187072627;17506.912465437847;17518.474445766646;441676;445830;448666;449088;450315;63881.68059159674;64252;64364.67817660882;64421.35578644247;64558;1364.747521708487;1392.373431955624;1461;1482.577465422371;1501.0768377447791;30234.84882575661;30627.846860765552;30824.69175308308;31315;32120.67879321177;50198.49801502084;50587;50845.49154508408;50860.74569627128;50938.49061509486;291244;292578;293749;293849;294129;36427.07885501746;37620.435693464075;37754.811225944795;37835.432468512445;37863.62136378601;48108;49098;49115;49375;49716;40627;41143.7942810296;41174.38238426366;41524.79237603793;42355.576444236394;39839;40456.59543404528;40636.79681601573;40687.09969818641;41103.17793644051;34354.141146471535;34534.30931381408;35036.474452882714;35056;35409;35977.46033809547;36470;37747.62252377441;40839;41951.370729439695;27312.590311145745;27542.17373478799;27719.86140069287;27781;27785.86107069452 15:16:57 INFO - 2015-12-23 15:16:57,837 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.03956357398596;359.02524645587226;364.8234443918362;376.92838360711465;381.769029737009;13217;16119.597010074725;18379.72430413545;20526.486837829045;21321.57356852862;20085.698714519294;20089.497762555944;20457.488562785937;20465.897670511666;20490.2828401006;5769.740361683724;5900.911486327708;5902.3507414184405;5923.644581325113;5925.822225333247;4354.1074079813725;4521.502634710179;5803.448672376117;6292.622442653455;6496.058071579618;6354.237491501026;6478.870422591545;6504.349565043505;6520.380563846427;6535.673216339188;6171.567990240685;6183.2889217740085;6201.720922558473;6211.285702144243;6213.130161777355;5574.749136288877;5586.804461843816;5591.077472217079;5598.580106491989;5845.7369418376275;23862.52274954491;24617.753822461724;24813;24854.751452485423;25031.749682503123;2285.988570057156;2289.2216646340157;2353.1646265575646;2356.328446392787;2442.46265821519;14397.136171829723;14510.274486275644;14518.564443066725;14610.790414073976;14680.412783488755;1768.478298901827;1803.2877013579694;1824.7262910563522;1839.5768973136253;1888.8383644058943;72851.63574182095;72868.27131728668;75171.24828751697;75248;75285;11544.59593914209;11898.286102833858;11996.880031199751;11999.820002699966;12014.159008869296;16202.513924582281;16214.270357833926;16265.918670406572;16266.268017939221;16270.430534931458;8455.577221138918;8491.532965686947;8504.14958504157;8617.396782225183;8666.436681615674;8063.677452901936;8197.221263979913;8205.753827385188;8260.876086858701;8287.62705678246;6866.862662746717;6964.477664175207;6973.267806880306;7001;7001.9147032210085;1741.9839892568302;1994.262123014494;2049.7437820272467;2072.2332736887447;2074.543600407938;494.90101979604225;504.14295697314714;536.5680627095226;556.0824639345113;576.3026737647493;11454.427278636034;12841.93579032099;12906.320259805147;13297.933510332387;13539.729205416033;530.4776777967855;540.2922171954751;547.1874266713927;562.8170844475562;576.4437317988078;6355.3644635536075;6748.797536073926;7242;7394.519356241827;7433.182349941502;631685;648897;652373;674887;680982;84790.57604711936;86392;87031.1296887023;88599;91735;593.4273426143792;593.4984937727527;596.558546675457;596.9582129250998;601.8736065426222;11997.940010299893;12139.210951288138;12159.270443773403;12195.085368597212;12218.511259549874;37042.07394815151;37466.43800342942;37547.43678844765;39039;39066;51977.22034169415;53066.469335307695;53302.93394132018;53341.19988200103;54017.729911350194;7530.585817780075;7936.722214722457;7940.801479963047;7946.642401091849;7947.682092716226;13863.376148073563;13961.301934903417;14046.648833779238;14147.858521415066;14539.781903271249;12223.266604003788;12352.197107188003;12380.133390662928;12540.749185016066;12611.243325400139;11975.820362694392;12310.261384316611;12362.876371236533;12481.56314528987;12641.93679031599;12370.505179792708;12407.25556466615;12504.124711270124;12558.246505209716;12568.937155314165;7769.72805951789;7833.295003449489;7906.802329941797;7909.446338756232;7920;11779.705507362385;11958.342291174069;11996.16026878145;12028.09789265791;12219.633410997685 15:16:57 INFO - 2015-12-23 15:16:57,837 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1540.2991638804344;1552.9922350388247;1750;1795.4523870219582;1823.6990896502073;7649.92350076499;7932.881006784888;7935.88096178557;7939.642716077776;7947.403944704141;7265.346118849303;7416.406687465003;7880.172581878906;7890.092639346477;8093.190680931903;2324.2910912171783;2332.4985128197436;2359.976400235993;2363.2555744940287;2365.988170059156;2114.9154033838627;2388.737238903719;2403.831731778776;2437.122635851092;2442.5725498037773;1992.4919145617848;2323.674685544025;2373.7507561705943;2374.133441293924;2374.3114496795906;2341.2039906431896;2356.9410764730846;2372.2764556810166;2373.560891235127;2378.0725517048363;2324.8140148788057;2330.906763729449;2351.8588884666974;2357.799587035104;2358.681577986967;8120.472169308976;8186.304164146055;8188.631511581933;8189.181081891823;8197.79505512361;5023.2214006829;5110.28456016158;5110.846674599731;5111.284420181178;5111.539961403491;3195.808251504894;3213.260949981494;3234.6118465784257;3290.835458227079;3309.238875058726;1060.7454210989306;1907.4711783200967;1922.9326973555999;1927.363969889933;1941.1167918597005;11245.662630121109;11399.316041037564;11403.771924561463;11406.486708098155;11422.771544569145;4376.627986621109;4398.538153493901;4444.866654000385;4466.553344665507;4467.8212871484775;9168.954155229181;9215.815683686289;9231.90768092324;9259.629614815332;9277.675281365255;5009.724465154379;5009.949900501021;5015;5024.874378140576;5029.949700502948;4666.416697912761;4667.906641867144;4678.1813182692895;4685.086408150378;4688.64835137366;3571;3595.96987888677;3596.0650230940146;3621.27574485104;3625.963740362615;1036.9014943580348;1069.5294070608907;1070.3203465799118;1101.9063379612824;1112.0991996482874;1088.4992903264429;1131.0499180688262;1138.732397886496;1142.662914440242;1191.642507247822;7672.923270767333;7827.295543401121;7858.489198202098;7916.525008499509;7919.920800792033;1116.547798141754;1158.2645020412099;1199.4362649554696;1214.7752665756864;1218.780619488483;6283.901036243758;6336.239651241881;6348.682565871688;6378.425941665179;6383.3616638336725;9829.803403932025;9972.850407243897;10001.799964000826;10003.44981026036;10015.298929074896;9798;9912.405255684538;9994.450305233282;10013.198944084601;10019.549120289605;7722.266384693445;7765.844683106307;7794.727184548514;7797.025371828521;7816.648250828613;7404.037475128414;7472.103347598323;7501.924980750123;7537.321641052332;7549;8696.347773917108;8831.690890819047;8961.059088795582;8967.910320896708;9005.594748236213;8922.375433719837;8975.147361000694;8990.505522196341;9001.234895033995;9202.125798049443;7031.22656507794;7112.324329188718;7123.109611298588;7123.180834204133;7130.2156762757095;7715.074191097105;7816.4137689674635;7879.763607091796;7889.684412623431;7906.52560846351;7501.32488076054;7524.699012039458;7535.208803075818;7560.3573696236435;7656.272654097852;6171.551771464049;6525.510586706111;6725.865482690221;6933.1333583302085;7102.857942841218;6960.895586566105;6991.825204369931;6992.895106573304;6995.615241161785;6998.930010700032;6742.764003259862;6786.355296246848;6894.207166175754;6929.861402772017;6944.930550694428;7042.260562640849;7069.752558660422;7071.82320441993;7073.292670732885;7076.964615176891 15:16:57 INFO - 2015-12-23 15:16:57,837 DEBUG : BROWSER_OUTPUT: |4;prototype.html;351.1204432895596;354.73040489228185;356.8251556737198;357.93020361029596;359.69066602721654;5877.471027607515;5882.735276912533;5884.205632239647;5889.587728858982;5932.1991531143285;5823.621464604808;5826.067829147337;5833.095870140124;5834.941650583492;5835.649861008343;4529.162105010567;4555.840545580905;4578.839740609079;4598.908021839561;4660.440747110351;4615.8846028849375;4640.280756482751;4652.883677908046;4664.183767840631;4672.55610716983;4587.311903214511;4597.494275629678;4598.195315819735;4599.517050709677;4610.101030299093;4411.933820992687;4472.709273897202;4599.885002874938;4625.398698169251;4637.976810115928;4110.671146308288;4119.8352065917325;4121.175764847043;4127.4015267786;4139.855105071339;5481.917771233435;5560.193771903051;5615.550755939515;5625.296837895263;5649.350324712628;5329.440408757102;5381.408045115034;5407.83776486706;5411.918821217685;5412.404635490093;6195.845103872394;6231.376862313778;6244.531660125509;6255.593386429868;6264.718087686065;4562.908741825145;4563.703359281636;4564.155631208204;4564.885877853047;4573.49691533931;6222.408871157233;6412.230532336102;6434.871302573969;6499.642519661416;6504.902426463606;4701.976490117596;4725.1730947084125;4725.314829349793;4725.69282996611;4747.382840230817;4800.159972004885;4803.807847686054;4806.399200099988;4807.711537307773;4808.735519546389;5293.550048245942;5413.702246376487;5429.131338985743;5438.564914806806;5444.3194600674915;6297.1813664223355;6303.716332765037;6303.905441418382;6305.14880491137;6305.716242769086;1717.828217178272;1726;1728.7925448945955;1736.2881218700272;1736.3054778088613;1179.0803173524664;1180.0677464802882;1182.4501606752729;1186.063010221915;1212.2665787198794;6106.603070800384;6122.051082082285;6123.877522449526;6128.908066379008;6133.355997620275;6525.510586706016;6533.150690410216;6534.085228068075;6535.052417399451;6536.281009088996;5989.970050149808;6075.210222671025;6104.38956104386;6115.449609535163;6117.327094019654;3585.8027808470265;3610.711143108545;3618.656227658368;3619.4570814378053;3621.9637803621627;6684;6724.293949135368;6740.460763138937;6744.831379215559;6745.730170793211;6485.0272459130565;6565.868682626417;6596.307387724316;6679.56582822115;6706.698198581075;6684.532082754258;6709.865802683919;6712.53012289135;6732.798016059526;6741.662916854236;6443.065755465525;6469.482441404771;6484.643344616092;6487.18910136233;6495.188101487314;6300.338464461165;6487.156669632917;6503.187101612299;6506.3818937200895;6509.544331896722;6808.863822723617;6811.284815094542;6815.079964204769;6817.113775209191;6819;4745.430548334222;4753.714777113384;4758.381410416624;4760.78576464053;4761.500042495488;6481.902771458337;6605.702743376656;6609.735610575523;6612.206535215805;6613.338666133541;6442.935570644234;6443.516736244706;6449.580777249464;6452.12896258999;6458.418742313214;6428.453581445629;6429.839254018687;6431.099646049463;6433.646149461824;6434.163558737334;6224.50355265069;6297.118403423433;6324.747010119544;6326.2092238470195;6338.207724034496;6410.679466026773;6456.451201647725;6462.127612772216;6462.386073323027;6464.062710906798;6299.779507717208;6309.779157729457;6312.40032196959;6312.936870631235;6313.05304204373 15:16:57 INFO - 2015-12-23 15:16:57,838 DEBUG : BROWSER_OUTPUT: |5;yui.html;863.6027427383403;935.0836180543067;949.8290307744612;954.0888451528789;958.5111593087523;15355.923220383896;15529.767053494192;15610.219489025532;15631.531054068368;15665.530034098967;14723.705525889476;14773;14783.334749936252;14795.33420996055;14823.629409264773;873.6112429968666;894.838928992781;899.4872922434215;899.6626265150569;900.5587262241487;900.522722956834;903.5166186090443;905.1129892705153;905.755446029575;907.2787134228304;938.8544775559765;941.948192849393;946.9952650236739;947.0387556630026;947.2895328503622;938.502593625383;939.9060093990614;940.3041749105613;941.3975055964188;941.472775245861;684.0594182998377;687.3023880761032;687.5324779150176;687.7180356053993;688.1810645332039;15177.6205594861;15209.695806083817;15252.923735381362;15253;15384.384624615002;11311.264767790068;11355.602553910574;11397.430128493543;11406.372649504274;11605;10434.269601127924;10453.84319235212;10569.577216911315;10610.946945265225;11504.194706370557;3421.298633780069;3451.3097380523996;3452.499387588811;3460.584729832436;3519.3137338219058;43159.136817263796;43476.130477390274;48637.75681121607;49173.75413122947;49726.50273497255;8219.671213151407;8458.40791144614;8461.23848853606;8473.661053557911;8798.9881163667;13615.183089014492;13630.522931697542;13635.727285454235;13646.044776865525;13752.724945501033;7270.491065625355;7277.163126240444;7344.669489872968;7406.666699998513;7653.57905315213;4507.5267096955;4564.977175114103;4585.082983403266;4717.150912835723;4749.050189962021;2982.8508574571183;3006.038067818321;3012.0662594595747;3014.954775678367;3017.0345489443166;466.0981001761602;483.2316616579655;490.2156549520738;491.42257847029595;491.52322247419943;541.1978112884086;575.1085816983742;592.1946153231602;614.649649699667;639.6737663791407;8013.559254240956;8036.397270204758;8161.79595510117;8323.66705331792;8644.524551149747;511.88482591416636;605.9214598015451;614.8611339113849;637.8660481298887;658.3712554510431;4040.4949381327333;4042.2319759245647;4103.630673239363;4115.508063836058;4139.606737360006;69339.30660693429;70125.64937175282;70128;73902;74644;58275.41724582785;59067.40932590705;59197;59487.40512594905;60470.697646511486;45740.5425945743;49169;56541.71729141328;57173.71413142908;57201.71399143061;8428.662853485792;8546.059933407196;8612.61243244043;8618.655253789779;8625.39622226438;40489.79755101206;40649;41796.79101604473;41978.37032444455;42779.35830962476;50929.745351273006;53928.730356347965;54065;54550.454495454534;54771.45228547663;8371.497710137412;8793.428427152214;8880.28957683371;8979.281657467256;9120.5439728012;13352.732945341233;13400.396982136022;13508.932455337661;13514.526991555249;13747.793783093062;10988.560457581607;11096.5561377544;11414.48634811419;11600.6519804406;11955.701107472383;12432.564860229848;12447.93776031114;12525.123241373016;12551;12823.807642885551;11470.369129697947;11806.468708907947;11813.232139910879;12011.939940300592;12120.18417731291;10682.679519614425;10758.13934885223;10767.696909272345;10961.397123158304;11023.0630396414;10239.7440063999;10456.268061235945;10473.423961681876;10474.581016759244;10508.21188410857 15:16:57 INFO - 2015-12-23 15:16:57,839 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:16:57 INFO - 2015-12-23 15:16:57,839 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,871 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: __startTimestamp1450912617829__endTimestamp 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5692.07 Median:5943.14 stddev:649.93 (10.9%) stddev-sans-first:623.20 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: Values: 3256.0 3391.9 3402.6 3409.3 3426.4 5631.1 5646.8 5659.0 5661.1 5716.6 5676.7 5688.3 5696.3 5699.5 5703.9 5036.5 5124.2 5133.4 5189.4 5229.2 5220.8 5243.3 5260.4 5261.5 5269.8 5167.6 5186.2 5211.5 5216.3 5289.7 5184.6 5264.5 5268.2 5274.7 5276.6 5159.1 5195.0 5229.6 5236.4 5238.9 5308.4 5358.7 5413.5 5487.8 5498.8 6118.4 6196.3 6199.8 6208.4 6214.4 6213.4 6225.6 6244.8 6250.7 6251.8 5537.4 5647.9 5667.9 5742.5 5773.7 6215.4 6241.2 6264.7 6268.1 6275.8 5943.1 6077.0 6088.1 6091.5 6096.2 6112.0 6124.6 6208.6 6216.4 6217.2 6098.9 6172.5 6174.4 6181.6 6185.6 5915.3 6040.1 6140.6 6164.8 6178.9 4668.4 4678.2 4678.3 4682.6 4685.4 4109.1 4193.7 4195.7 4204.3 4223.7 5783.8 5807.1 5811.4 5812.5 5814.2 6074.7 6117.4 6154.5 6160.3 6164.6 5623.0 5697.7 5720.0 5729.1 5778.4 5306.9 5311.1 5313.7 5314.4 5331.3 6120.7 6216.7 6410.0 6410.8 6413.9 6036.8 6081.8 6092.0 6098.0 6139.0 6280.0 6503.4 6508.4 6510.4 6511.0 5929.4 6028.3 6054.5 6187.5 6200.7 6087.4 6102.1 6102.7 6105.1 6114.5 5956.2 6015.1 6021.3 6021.4 6024.9 5779.3 5898.0 5996.3 6041.1 6144.2 5982.6 5989.7 5991.3 5997.7 6002.5 6050.2 6061.6 6066.3 6072.8 6080.2 5642.2 5747.4 5916.9 6002.5 6013.7 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,840 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:55063.42 Median:35046.24 stddev:96432.33 (275.2%) stddev-sans-first:96616.09 15:16:57 INFO - 2015-12-23 15:16:57,841 DEBUG : BROWSER_OUTPUT: Values: 581.4 617.8 620.7 626.5 636.6 43831.6 44994.0 45073.8 45127.8 45143.3 47519.0 48147.3 48148.3 48159.3 48186.0 8242.5 8368.0 9244.9 9465.5 10760.5 10999.1 11089.8 11095.5 11119.8 11124.6 11030.2 11125.8 11142.3 11146.2 11148.1 11070.3 11127.2 11133.3 11135.9 11151.8 10927.1 11023.6 11051.4 11054.7 11059.5 43516.0 43568.6 43963.8 44029.3 44048.6 3502.1 3573.7 3587.2 3806.4 3853.0 33818.7 33849.8 33901.0 34262.8 34751.5 3311.7 3370.3 3397.6 3418.1 3438.5 309832.0 310075.0 310153.0 311085.0 447536.0 58489.0 75390.6 76901.6 77016.6 77049.2 71815.6 71834.0 72306.0 72344.6 72392.3 16440.0 16566.3 16653.2 16674.3 16682.8 12513.3 12652.6 12669.8 12684.4 12694.7 22737.3 23037.0 23086.8 23126.4 23126.8 8454.9 8621.7 8710.3 8817.9 8857.7 917.4 975.7 980.6 1064.5 1186.9 29925.7 30275.0 30498.5 30762.5 30764.3 1051.8 1121.1 1167.4 1185.0 1246.2 14921.3 15841.7 16920.3 17506.9 17518.5 441676.0 445830.0 448666.0 449088.0 450315.0 63881.7 64252.0 64364.7 64421.4 64558.0 1364.7 1392.4 1461.0 1482.6 1501.1 30234.8 30627.8 30824.7 31315.0 32120.7 50198.5 50587.0 50845.5 50860.7 50938.5 291244.0 292578.0 293749.0 293849.0 294129.0 36427.1 37620.4 37754.8 37835.4 37863.6 48108.0 49098.0 49115.0 49375.0 49716.0 40627.0 41143.8 41174.4 41524.8 42355.6 39839.0 40456.6 40636.8 40687.1 41103.2 34354.1 34534.3 35036.5 35056.0 35409.0 35977.5 36470.0 37747.6 40839.0 41951.4 27312.6 27542.2 27719.9 27781.0 27785.9 15:16:57 INFO - 2015-12-23 15:16:57,841 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,842 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33032.41 Median:4437.81 stddev:107644.33 (2425.6%) stddev-sans-first:107918.34 15:16:57 INFO - 2015-12-23 15:16:57,842 DEBUG : BROWSER_OUTPUT: Values: 357.0 359.0 364.8 376.9 381.8 13217.0 16119.6 18379.7 20526.5 21321.6 20085.7 20089.5 20457.5 20465.9 20490.3 5769.7 5900.9 5902.4 5923.6 5925.8 4354.1 4521.5 5803.4 6292.6 6496.1 6354.2 6478.9 6504.3 6520.4 6535.7 6171.6 6183.3 6201.7 6211.3 6213.1 5574.7 5586.8 5591.1 5598.6 5845.7 23862.5 24617.8 24813.0 24854.8 25031.7 2286.0 2289.2 2353.2 2356.3 2442.5 14397.1 14510.3 14518.6 14610.8 14680.4 1768.5 1803.3 1824.7 1839.6 1888.8 72851.6 72868.3 75171.2 75248.0 75285.0 11544.6 11898.3 11996.9 11999.8 12014.2 16202.5 16214.3 16265.9 16266.3 16270.4 8455.6 8491.5 8504.1 8617.4 8666.4 8063.7 8197.2 8205.8 8260.9 8287.6 6866.9 6964.5 6973.3 7001.0 7001.9 1742.0 1994.3 2049.7 2072.2 2074.5 494.9 504.1 536.6 556.1 576.3 11454.4 12841.9 12906.3 13297.9 13539.7 530.5 540.3 547.2 562.8 576.4 6355.4 6748.8 7242.0 7394.5 7433.2 631685.0 648897.0 652373.0 674887.0 680982.0 84790.6 86392.0 87031.1 88599.0 91735.0 593.4 593.5 596.6 597.0 601.9 11997.9 12139.2 12159.3 12195.1 12218.5 37042.1 37466.4 37547.4 39039.0 39066.0 51977.2 53066.5 53302.9 53341.2 54017.7 7530.6 7936.7 7940.8 7946.6 7947.7 13863.4 13961.3 14046.6 14147.9 14539.8 12223.3 12352.2 12380.1 12540.7 12611.2 11975.8 12310.3 12362.9 12481.6 12641.9 12370.5 12407.3 12504.1 12558.2 12568.9 7769.7 7833.3 7906.8 7909.4 7920.0 11779.7 11958.3 11996.2 12028.1 12219.6 15:16:57 INFO - 2015-12-23 15:16:57,842 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,842 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5732.70 Median:6227.73 stddev:2944.62 (47.3%) stddev-sans-first:2936.01 15:16:57 INFO - 2015-12-23 15:16:57,843 DEBUG : BROWSER_OUTPUT: Values: 1540.3 1553.0 1750.0 1795.5 1823.7 7649.9 7932.9 7935.9 7939.6 7947.4 7265.3 7416.4 7880.2 7890.1 8093.2 2324.3 2332.5 2360.0 2363.3 2366.0 2114.9 2388.7 2403.8 2437.1 2442.6 1992.5 2323.7 2373.8 2374.1 2374.3 2341.2 2356.9 2372.3 2373.6 2378.1 2324.8 2330.9 2351.9 2357.8 2358.7 8120.5 8186.3 8188.6 8189.2 8197.8 5023.2 5110.3 5110.8 5111.3 5111.5 3195.8 3213.3 3234.6 3290.8 3309.2 1060.7 1907.5 1922.9 1927.4 1941.1 11245.7 11399.3 11403.8 11406.5 11422.8 4376.6 4398.5 4444.9 4466.6 4467.8 9169.0 9215.8 9231.9 9259.6 9277.7 5009.7 5009.9 5015.0 5024.9 5029.9 4666.4 4667.9 4678.2 4685.1 4688.6 3571.0 3596.0 3596.1 3621.3 3626.0 1036.9 1069.5 1070.3 1101.9 1112.1 1088.5 1131.0 1138.7 1142.7 1191.6 7672.9 7827.3 7858.5 7916.5 7919.9 1116.5 1158.3 1199.4 1214.8 1218.8 6283.9 6336.2 6348.7 6378.4 6383.4 9829.8 9972.9 10001.8 10003.4 10015.3 9798.0 9912.4 9994.5 10013.2 10019.5 7722.3 7765.8 7794.7 7797.0 7816.6 7404.0 7472.1 7501.9 7537.3 7549.0 8696.3 8831.7 8961.1 8967.9 9005.6 8922.4 8975.1 8990.5 9001.2 9202.1 7031.2 7112.3 7123.1 7123.2 7130.2 7715.1 7816.4 7879.8 7889.7 7906.5 7501.3 7524.7 7535.2 7560.4 7656.3 6171.6 6525.5 6725.9 6933.1 7102.9 6960.9 6991.8 6992.9 6995.6 6998.9 6742.8 6786.4 6894.2 6929.9 6944.9 7042.3 7069.8 7071.8 7073.3 7077.0 15:16:57 INFO - 2015-12-23 15:16:57,843 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,844 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5343.57 Median:6032.59 stddev:1556.83 (25.8%) stddev-sans-first:1515.43 15:16:57 INFO - 2015-12-23 15:16:57,844 DEBUG : BROWSER_OUTPUT: Values: 351.1 354.7 356.8 357.9 359.7 5877.5 5882.7 5884.2 5889.6 5932.2 5823.6 5826.1 5833.1 5834.9 5835.6 4529.2 4555.8 4578.8 4598.9 4660.4 4615.9 4640.3 4652.9 4664.2 4672.6 4587.3 4597.5 4598.2 4599.5 4610.1 4411.9 4472.7 4599.9 4625.4 4638.0 4110.7 4119.8 4121.2 4127.4 4139.9 5481.9 5560.2 5615.6 5625.3 5649.4 5329.4 5381.4 5407.8 5411.9 5412.4 6195.8 6231.4 6244.5 6255.6 6264.7 4562.9 4563.7 4564.2 4564.9 4573.5 6222.4 6412.2 6434.9 6499.6 6504.9 4702.0 4725.2 4725.3 4725.7 4747.4 4800.2 4803.8 4806.4 4807.7 4808.7 5293.6 5413.7 5429.1 5438.6 5444.3 6297.2 6303.7 6303.9 6305.1 6305.7 1717.8 1726.0 1728.8 1736.3 1736.3 1179.1 1180.1 1182.5 1186.1 1212.3 6106.6 6122.1 6123.9 6128.9 6133.4 6525.5 6533.2 6534.1 6535.1 6536.3 5990.0 6075.2 6104.4 6115.4 6117.3 3585.8 3610.7 3618.7 3619.5 3622.0 6684.0 6724.3 6740.5 6744.8 6745.7 6485.0 6565.9 6596.3 6679.6 6706.7 6684.5 6709.9 6712.5 6732.8 6741.7 6443.1 6469.5 6484.6 6487.2 6495.2 6300.3 6487.2 6503.2 6506.4 6509.5 6808.9 6811.3 6815.1 6817.1 6819.0 4745.4 4753.7 4758.4 4760.8 4761.5 6481.9 6605.7 6609.7 6612.2 6613.3 6442.9 6443.5 6449.6 6452.1 6458.4 6428.5 6429.8 6431.1 6433.6 6434.2 6224.5 6297.1 6324.7 6326.2 6338.2 6410.7 6456.5 6462.1 6462.4 6464.1 6299.8 6309.8 6312.4 6312.9 6313.1 15:16:57 INFO - 2015-12-23 15:16:57,844 DEBUG : BROWSER_OUTPUT: 15:16:57 INFO - 2015-12-23 15:16:57,844 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15048.61 Median:24563.99 stddev:18708.48 (76.2%) stddev-sans-first:18730.64 15:16:57 INFO - 2015-12-23 15:16:57,845 DEBUG : BROWSER_OUTPUT: Values: 863.6 935.1 949.8 954.1 958.5 15355.9 15529.8 15610.2 15631.5 15665.5 14723.7 14773.0 14783.3 14795.3 14823.6 873.6 894.8 899.5 899.7 900.6 900.5 903.5 905.1 905.8 907.3 938.9 941.9 947.0 947.0 947.3 938.5 939.9 940.3 941.4 941.5 684.1 687.3 687.5 687.7 688.2 15177.6 15209.7 15252.9 15253.0 15384.4 11311.3 11355.6 11397.4 11406.4 11605.0 10434.3 10453.8 10569.6 10610.9 11504.2 3421.3 3451.3 3452.5 3460.6 3519.3 43159.1 43476.1 48637.8 49173.8 49726.5 8219.7 8458.4 8461.2 8473.7 8799.0 13615.2 13630.5 13635.7 13646.0 13752.7 7270.5 7277.2 7344.7 7406.7 7653.6 4507.5 4565.0 4585.1 4717.2 4749.1 2982.9 3006.0 3012.1 3015.0 3017.0 466.1 483.2 490.2 491.4 491.5 541.2 575.1 592.2 614.6 639.7 8013.6 8036.4 8161.8 8323.7 8644.5 511.9 605.9 614.9 637.9 658.4 4040.5 4042.2 4103.6 4115.5 4139.6 69339.3 70125.6 70128.0 73902.0 74644.0 58275.4 59067.4 59197.0 59487.4 60470.7 45740.5 49169.0 56541.7 57173.7 57201.7 8428.7 8546.1 8612.6 8618.7 8625.4 40489.8 40649.0 41796.8 41978.4 42779.4 50929.7 53928.7 54065.0 54550.5 54771.5 8371.5 8793.4 8880.3 8979.3 9120.5 13352.7 13400.4 13508.9 13514.5 13747.8 10988.6 11096.6 11414.5 11600.7 11955.7 12432.6 12447.9 12525.1 12551.0 12823.8 11470.4 11806.5 11813.2 12011.9 12120.2 10682.7 10758.1 10767.7 10961.4 11023.1 10239.7 10456.3 10473.4 10474.6 10508.2 15:16:57 INFO - 2015-12-23 15:16:57,845 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:16:57 INFO - 2015-12-23 15:16:57,845 DEBUG : BROWSER_OUTPUT: 15:16:58 INFO - 2015-12-23 15:16:58,438 INFO : Browser exited with error code: 0 15:16:58 INFO - 2015-12-23 15:16:58,462 INFO : Completed test dromaeo_css (00:18:30) 15:16:58 INFO - 2015-12-23 15:16:58,462 INFO : Starting test kraken 15:16:58 INFO - 2015-12-23 15:16:58,462 DEBUG : operating with platform_type : linux_ 15:16:58 INFO - 2015-12-23 15:16:58,462 INFO : Initialising browser for kraken test... 15:16:58 INFO - 2015-12-23 15:16:58,468 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpB1oHI_/profile http://localhost:45873/getInfo.html 15:17:02 INFO - 2015-12-23 15:17:02,410 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:17:02 INFO - 2015-12-23 15:17:02,410 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:17:02 INFO - 2015-12-23 15:17:02,410 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:17:02 INFO - 2015-12-23 15:17:02,410 DEBUG : BROWSER_OUTPUT: __metrics 15:17:02 INFO - 2015-12-23 15:17:02,527 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] 15:17:04 INFO - 2015-12-23 15:17:04,062 INFO : Browser initialized. 15:17:04 INFO - 2015-12-23 15:17:04,063 INFO : Running cycle 1/1 for kraken test... 15:17:04 INFO - 2015-12-23 15:17:04,063 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpB1oHI_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:17:15 INFO - 2015-12-23 15:17:15,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 128663552 15:17:15 INFO - 2015-12-23 15:17:15,597 DEBUG : BROWSER_OUTPUT: 15:17:18 INFO - 2015-12-23 15:17:18,035 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45873/tests/kraken/driver.html?testName=audio-beat-detection) 15:17:18 INFO - 2015-12-23 15:17:18,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 162582528 15:17:18 INFO - 2015-12-23 15:17:18,603 DEBUG : BROWSER_OUTPUT: 15:17:21 INFO - 2015-12-23 15:17:21,170 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45873/tests/kraken/driver.html?testName=audio-dft) 15:17:21 INFO - 2015-12-23 15:17:21,742 DEBUG : BROWSER_OUTPUT: RSS: Main: 161460224 15:17:21 INFO - 2015-12-23 15:17:21,743 DEBUG : BROWSER_OUTPUT: 15:17:24 INFO - 2015-12-23 15:17:24,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45873/tests/kraken/driver.html?testName=audio-fft) 15:17:25 INFO - 2015-12-23 15:17:25,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 158138368 15:17:25 INFO - 2015-12-23 15:17:25,082 DEBUG : BROWSER_OUTPUT: 15:17:27 INFO - 2015-12-23 15:17:27,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45873/tests/kraken/driver.html?testName=audio-oscillator) 15:17:27 INFO - 2015-12-23 15:17:27,580 DEBUG : BROWSER_OUTPUT: RSS: Main: 158035968 15:17:27 INFO - 2015-12-23 15:17:27,580 DEBUG : BROWSER_OUTPUT: 15:17:29 INFO - 2015-12-23 15:17:29,718 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45873/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:17:30 INFO - 2015-12-23 15:17:30,279 DEBUG : BROWSER_OUTPUT: RSS: Main: 156598272 15:17:30 INFO - 2015-12-23 15:17:30,279 DEBUG : BROWSER_OUTPUT: 15:17:33 INFO - 2015-12-23 15:17:33,137 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45873/tests/kraken/driver.html?testName=imaging-darkroom) 15:17:33 INFO - 2015-12-23 15:17:33,698 DEBUG : BROWSER_OUTPUT: RSS: Main: 155594752 15:17:33 INFO - 2015-12-23 15:17:33,699 DEBUG : BROWSER_OUTPUT: 15:17:37 INFO - 2015-12-23 15:17:37,066 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45873/tests/kraken/driver.html?testName=imaging-desaturate) 15:17:37 INFO - 2015-12-23 15:17:37,633 DEBUG : BROWSER_OUTPUT: RSS: Main: 157519872 15:17:37 INFO - 2015-12-23 15:17:37,633 DEBUG : BROWSER_OUTPUT: 15:17:40 INFO - 2015-12-23 15:17:40,334 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45873/tests/kraken/driver.html?testName=json-parse-financial) 15:17:40 INFO - 2015-12-23 15:17:40,908 DEBUG : BROWSER_OUTPUT: RSS: Main: 156004352 15:17:40 INFO - 2015-12-23 15:17:40,908 DEBUG : BROWSER_OUTPUT: 15:17:42 INFO - 2015-12-23 15:17:42,407 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45873/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:17:42 INFO - 2015-12-23 15:17:42,969 DEBUG : BROWSER_OUTPUT: RSS: Main: 163082240 15:17:42 INFO - 2015-12-23 15:17:42,969 DEBUG : BROWSER_OUTPUT: 15:17:44 INFO - 2015-12-23 15:17:44,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:17:45 INFO - 2015-12-23 15:17:45,266 DEBUG : BROWSER_OUTPUT: RSS: Main: 160391168 15:17:45 INFO - 2015-12-23 15:17:45,267 DEBUG : BROWSER_OUTPUT: 15:17:47 INFO - 2015-12-23 15:17:47,218 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:17:47 INFO - 2015-12-23 15:17:47,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 166408192 15:17:47 INFO - 2015-12-23 15:17:47,787 DEBUG : BROWSER_OUTPUT: 15:17:50 INFO - 2015-12-23 15:17:50,080 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:17:50 INFO - 2015-12-23 15:17:50,662 DEBUG : BROWSER_OUTPUT: RSS: Main: 169189376 15:17:50 INFO - 2015-12-23 15:17:50,663 DEBUG : BROWSER_OUTPUT: 15:17:53 INFO - 2015-12-23 15:17:53,077 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:17:53 INFO - 2015-12-23 15:17:53,638 DEBUG : BROWSER_OUTPUT: RSS: Main: 166744064 15:17:53 INFO - 2015-12-23 15:17:53,638 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,246 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45873/tests/kraken/driver.html?testName=ai-astar) 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: RSS: Main: 224493568 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;112;104;114;110;111;100;109;110;109 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;122;144;161;129;146;147;124;168;123 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;159;161;160;160;160;162;160;160 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;72;139;72;72;72;72;153;70 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;88;88;143;88;89;89;137;88;89;132 15:17:55 INFO - 2015-12-23 15:17:55,497 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;110;109;109;109;109;109;109 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;145;146;145;145;145;145;145 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;95;96;95;96 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;54;53;55;53;54;54;55;52;53 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;61;62;61;62;61;62;61;61;61;61 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;79;78;78;79;77;77;79;78;77 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;111;116;111;107;109;109;112;122;109;115 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;137;138;139;137;137;148;135;136;130 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;60;61;61;61;62;62;63;61;60;64 15:17:55 INFO - 2015-12-23 15:17:55,498 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:17:55 INFO - 2015-12-23 15:17:55,499 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:17:55 INFO - 2015-12-23 15:17:55,499 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,881 15:17:55 INFO - 2015-12-23 15:17:55,499 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:17:55 INFO - 2015-12-23 15:17:55,499 DEBUG : BROWSER_OUTPUT: __startTimestamp1450912675495__endTimestamp 15:17:55 INFO - 2015-12-23 15:17:55,499 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.00 Median:110.50 stddev:4.08 (3.7%) stddev-sans-first:4.27 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: Values: 111.0 112.0 104.0 114.0 110.0 111.0 100.0 109.0 110.0 109.0 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.10 Median:146.50 stddev:16.17 (11.0%) stddev-sans-first:17.01 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: Values: 147.0 122.0 144.0 161.0 129.0 146.0 147.0 124.0 168.0 123.0 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 15:17:55 INFO - 2015-12-23 15:17:55,503 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 159.0 161.0 160.0 160.0 160.0 162.0 160.0 160.0 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.40 Median:72.00 stddev:31.59 (43.9%) stddev-sans-first:33.01 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 72.0 139.0 72.0 72.0 72.0 72.0 153.0 70.0 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.10 Median:88.00 stddev:23.77 (27.0%) stddev-sans-first:24.58 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: Values: 88.0 88.0 143.0 88.0 89.0 89.0 137.0 88.0 89.0 132.0 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 15:17:55 INFO - 2015-12-23 15:17:55,504 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.03 (1.9%) stddev-sans-first:1.00 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: Values: 55.0 54.0 53.0 55.0 53.0 54.0 54.0 55.0 52.0 53.0 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: Values: 61.0 62.0 61.0 62.0 61.0 62.0 61.0 61.0 61.0 61.0 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:78.00 stddev:1.03 (1.3%) stddev-sans-first:0.87 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: Values: 76.0 79.0 78.0 78.0 79.0 77.0 77.0 79.0 78.0 77.0 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.10 Median:111.50 stddev:4.46 (4.0%) stddev-sans-first:4.71 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: Values: 111.0 116.0 111.0 107.0 109.0 109.0 112.0 122.0 109.0 115.0 15:17:55 INFO - 2015-12-23 15:17:55,505 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,506 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.40 Median:137.00 stddev:4.45 (3.2%) stddev-sans-first:4.72 15:17:55 INFO - 2015-12-23 15:17:55,506 DEBUG : BROWSER_OUTPUT: Values: 137.0 137.0 138.0 139.0 137.0 137.0 148.0 135.0 136.0 130.0 15:17:55 INFO - 2015-12-23 15:17:55,506 DEBUG : BROWSER_OUTPUT: 15:17:55 INFO - 2015-12-23 15:17:55,506 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:1.27 (2.1%) stddev-sans-first:1.22 15:17:55 INFO - 2015-12-23 15:17:55,506 DEBUG : BROWSER_OUTPUT: Values: 60.0 61.0 61.0 61.0 62.0 62.0 63.0 61.0 60.0 64.0 15:17:55 INFO - 2015-12-23 15:17:55,506 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:17:55 INFO - 2015-12-23 15:17:55,506 DEBUG : BROWSER_OUTPUT: 15:17:56 INFO - 2015-12-23 15:17:56,194 INFO : Browser exited with error code: 0 15:17:56 INFO - 2015-12-23 15:17:56,253 INFO : Completed test kraken (00:00:57) 15:17:56 INFO - 2015-12-23 15:17:56,254 INFO : Starting test v8_7 15:17:56 INFO - 2015-12-23 15:17:56,254 DEBUG : operating with platform_type : linux_ 15:17:56 INFO - 2015-12-23 15:17:56,254 INFO : Initialising browser for v8_7 test... 15:17:56 INFO - 2015-12-23 15:17:56,260 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXg1qYp/profile http://localhost:45873/getInfo.html 15:18:00 INFO - 2015-12-23 15:18:00,321 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:18:00 INFO - 2015-12-23 15:18:00,321 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:18:00 INFO - 2015-12-23 15:18:00,321 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:18:00 INFO - 2015-12-23 15:18:00,321 DEBUG : BROWSER_OUTPUT: __metrics 15:18:00 INFO - 2015-12-23 15:18:00,483 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] 15:18:02 INFO - 2015-12-23 15:18:02,174 INFO : Browser initialized. 15:18:02 INFO - 2015-12-23 15:18:02,175 INFO : Running cycle 1/1 for v8_7 test... 15:18:02 INFO - 2015-12-23 15:18:02,175 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXg1qYp/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:18:13 INFO - 2015-12-23 15:18:13,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 128663552 15:18:13 INFO - 2015-12-23 15:18:13,630 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,017 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45873/tests/v8_7/run.html (next: http://localhost:45873/tests/v8_7/run.html) 15:18:35 INFO - 2015-12-23 15:18:35,267 DEBUG : BROWSER_OUTPUT: RSS: Main: 415887360 15:18:35 INFO - 2015-12-23 15:18:35,267 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,267 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:18:35 INFO - 2015-12-23 15:18:35,268 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:18:35 INFO - 2015-12-23 15:18:35,268 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:18:35 INFO - 2015-12-23 15:18:35,268 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:18:35 INFO - 2015-12-23 15:18:35,268 DEBUG : BROWSER_OUTPUT: |0;Richards;162.20600162206003 15:18:35 INFO - 2015-12-23 15:18:35,268 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;169.4915254237288 15:18:35 INFO - 2015-12-23 15:18:35,269 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.3476310625175 15:18:35 INFO - 2015-12-23 15:18:35,269 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5025.125628140703 15:18:35 INFO - 2015-12-23 15:18:35,269 DEBUG : BROWSER_OUTPUT: |4;RayTrace;948.7666034155598 15:18:35 INFO - 2015-12-23 15:18:35,269 DEBUG : BROWSER_OUTPUT: |5;Earley;555.247084952804 15:18:35 INFO - 2015-12-23 15:18:35,269 DEBUG : BROWSER_OUTPUT: |6;Boyer;10453.60824742268 15:18:35 INFO - 2015-12-23 15:18:35,270 DEBUG : BROWSER_OUTPUT: |7;RegExp;24119.04761904762 15:18:35 INFO - 2015-12-23 15:18:35,270 DEBUG : BROWSER_OUTPUT: |8;Splay;530.2226935312831 15:18:35 INFO - 2015-12-23 15:18:35,270 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 15:18:35 INFO - 2015-12-23 15:18:35,270 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:18:35 INFO - 2015-12-23 15:18:35,270 DEBUG : BROWSER_OUTPUT: __startTimestamp1450912715266__endTimestamp 15:18:35 INFO - 2015-12-23 15:18:35,270 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,271 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:18:35 INFO - 2015-12-23 15:18:35,271 DEBUG : BROWSER_OUTPUT: Number of tests: 10 15:18:35 INFO - 2015-12-23 15:18:35,271 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,271 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.21 Median:162.21 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,271 DEBUG : BROWSER_OUTPUT: Values: 162.2 15:18:35 INFO - 2015-12-23 15:18:35,271 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,272 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:169.49 Median:169.49 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,272 DEBUG : BROWSER_OUTPUT: Values: 169.5 15:18:35 INFO - 2015-12-23 15:18:35,272 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,272 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.35 Median:280.35 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,273 DEBUG : BROWSER_OUTPUT: Values: 280.3 15:18:35 INFO - 2015-12-23 15:18:35,273 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5025.13 Median:5025.13 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: Values: 5025.1 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:948.77 Median:948.77 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: Values: 948.8 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:555.25 Median:555.25 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: Values: 555.2 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10453.61 Median:10453.61 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: Values: 10453.6 15:18:35 INFO - 2015-12-23 15:18:35,274 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24119.05 Median:24119.05 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: Values: 24119.0 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:530.22 Median:530.22 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: Values: 530.2 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: Values: 4812.5 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:18:35 INFO - 2015-12-23 15:18:35,275 DEBUG : BROWSER_OUTPUT: 15:18:35 INFO - 2015-12-23 15:18:35,954 INFO : Browser exited with error code: 0 15:18:35 INFO - 2015-12-23 15:18:35,971 INFO : Completed test v8_7 (00:00:39) 15:18:36 INFO - 2015-12-23 15:18:36,288 INFO : Completed test suite (00:20:08) 15:18:36 INFO - 2015-12-23 15:18:36,288 DEBUG : Working with test: dromaeo_css 15:18:36 INFO - 2015-12-23 15:18:36,288 DEBUG : Generating results file: dromaeo_css 15:18:36 INFO - 2015-12-23 15:18:36,290 DEBUG : Working with test: kraken 15:18:36 INFO - 2015-12-23 15:18:36,290 DEBUG : Generating results file: kraken 15:18:36 INFO - 2015-12-23 15:18:36,291 INFO : javascript benchmark 15:18:36 INFO - 2015-12-23 15:18:36,291 DEBUG : Working with test: v8_7 15:18:36 INFO - 2015-12-23 15:18:36,291 DEBUG : Generating results file: v8_7 15:18:36 INFO - 2015-12-23 15:18:36,292 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:18:36 INFO - 2015-12-23 15:18:36,346 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 15:18:36 INFO - 2015-12-23 15:18:36,346 DEBUG : process_Request line: dromaeo_css 7336.41 graph.html#tests=[[72,53,35]] 15:18:36 INFO - 2015-12-23 15:18:36,346 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:18:36 INFO - 2015-12-23 15:18:36,376 DEBUG : process_Request line: kraken 1453.80 graph.html#tests=[[232,53,35]] 15:18:36 INFO - 2015-12-23 15:18:36,376 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:18:36 INFO - 2015-12-23 15:18:36,404 DEBUG : process_Request line: v8_7 23310.34 graph.html#tests=[[230,53,35]] 15:18:36 INFO - 2015-12-23 15:18:36,405 INFO : javascript benchmark 15:18:36 INFO - 2015-12-23 15:18:36,409 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3255.983720081399, 3391.9321613567745, 3402.6257111717705, 3409.267007593365, 3426.4175090234658, 5631.099024156136, 5646.830595082144, 5658.971705141479, 5661.122526008476, 5716.6284191527575, 5676.687782171979, 5688.345840228375, 5696.287964004499, 5699.458551437607, 5703.942960570393, 5036.521530454601, 5124.180131179012, 5133.3839939207255, 5189.403218629849, 5229.241759944805, 5220.791168353261, 5243.318368612094, 5260.395054568716, 5261.526462618363, 5269.8155564555245, 5167.560757335612, 5186.247994040862, 5211.5049070338255, 5216.347956505437, 5289.656172348804, 5184.637075404723, 5264.473552644743, 5268.236105764643, 5274.657147285414, 5276.604254680914, 5159.071367153911, 5194.97402512985, 5229.581633469314, 5236.423993360727, 5238.895222095537, 5308.362996440414, 5358.678479291255, 5413.458654134594, 5487.835364939058, 5498.780048798043, 6118.449339559416, 6196.318404975449, 6199.845003874894, 6208.379162083801, 6214.40963108504, 6213.409726076015, 6225.564210505267, 6244.843878903019, 6250.749970001195, 6251.812445626639, 5537.418571050022, 5647.915281270784, 5667.8583035424035, 5742.454466825645, 5773.6824474654295, 6215.440610345091, 6241.209739359428, 6264.655443950626, 6268.09112678659, 6275.811725648238, 5943.138244954457, 6077.0, 6088.086786981989, 6091.48222401092, 6096.207493025879, 6112.0, 6124.5712800103565, 6208.565400421927, 6216.44052035319, 6217.222847144107, 6098.939010609926, 6172.506199504029, 6174.351693072253, 6181.598196117328, 6185.628862268189, 5915.349311575723, 6040.124181993587, 6140.570160088841, 6164.7842325518395, 6178.938210617926, 4668.369770080995, 4678.2280923647895, 4678.2982552616695, 4682.601978831769, 4685.4143232095985, 4109.116539943926, 4193.706440549132, 4195.727277726999, 4204.285271503852, 4223.68322375823, 5783.826485205451, 5807.070868660994, 5811.418858114154, 5812.534997200214, 5814.244148260699, 6074.726637301332, 6117.357677443893, 6154.476869466056, 6160.291566469824, 6164.630122192593, 5623.044082505984, 5697.686627235542, 5719.971400143056, 5729.1119876419225, 5778.422157784188, 5306.9386122775595, 5311.123664595299, 5313.681179129265, 5314.388845282842, 5331.253624492574, 6120.741183502672, 6216.658083805343, 6409.9679501602195, 6410.775622853178, 6413.871722565523, 6036.8188954331445, 6081.787137450168, 6092.0, 6098.024316109401, 6138.969305153446, 6280.0, 6503.382178693017, 6508.414242718178, 6510.44661203803, 6511.0, 5929.436703513159, 6028.33688294296, 6054.455099041107, 6187.535934804998, 6200.658963757037, 6087.421694938974, 6102.115193296859, 6102.664353460602, 6105.084237364254, 6114.5414093942245, 5956.166136740946, 6015.097735339734, 6021.307549631673, 6021.42796434338, 6024.8795024100145, 5779.2775903012125, 5898.026825573904, 5996.340402555628, 6041.124037014696, 6144.17053697763, 5982.6410415373475, 5989.700514974146, 5991.3409524953095, 5997.670128142994, 6002.489788367863, 6050.183225264533, 6061.575689701678, 6066.3023752269055, 6072.757089716539, 6080.179175811209, 5642.181883626769, 5747.396523364986, 5916.940830591811, 6002.519798416203, 6013.699315034143], "unit": "ms", "name": "dojo.html", "value": 5648.695099090147}, {"lowerIsBetter": false, "replicates": [581.4331027248434, 617.8362733875526, 620.7020630097553, 626.4581137316221, 636.6434796513953, 43831.561684383145, 44994.0, 45073.77463112684, 45127.77436112819, 45143.322850157274, 47519.0, 48147.27779083316, 48148.2777758333, 48159.27761083587, 48186.0, 8242.464239824421, 8368.037675667285, 9244.907550924505, 9465.479398633073, 10760.515776790064, 10999.065079468255, 11089.778204435906, 11095.50070246841, 11119.833202501968, 11124.61063862765, 11030.172737044713, 11125.833112503318, 11142.27575207609, 11146.21976461644, 11148.108151347913, 11070.335779853194, 11127.165462590257, 11133.332000080021, 11135.888641113566, 11151.776964460705, 10927.071198948099, 11023.559057637685, 11051.447427628667, 11054.668359949215, 11059.502322395512, 43516.0, 43568.564314356765, 43963.78018109889, 44029.33955990663, 44048.55951440477, 3502.141975216088, 3573.749837511349, 3587.1749497615697, 3806.371948628501, 3852.97896057545, 33818.6618133818, 33849.830750846086, 33901.0, 34262.82868585666, 34751.4787278191, 3311.7350611950988, 3370.3427831572853, 3397.5583174187445, 3418.060033490785, 3438.48422736589, 309832.0, 310075.0, 310153.0, 311085.0, 447536.0, 58489.0, 75390.6230468855, 76901.61549192219, 77016.61491692506, 77049.2295077042, 71815.6409217961, 71834.0, 72306.0, 72344.63827680828, 72392.27607723961, 16440.0, 16566.337346506003, 16653.16734163311, 16674.333026678796, 16682.83317166837, 12513.311767852674, 12652.557160499338, 12669.809952850715, 12684.365781710878, 12694.746105078031, 22737.31788046361, 23037.0, 23086.769132308462, 23126.42183945381, 23126.768732312798, 8454.915450845412, 8621.7274296488, 8710.303175745925, 8817.911820881838, 8857.689980850639, 917.4128557723062, 975.707287813653, 980.5979548385128, 1064.499685147979, 1186.9169158158845, 29925.700742992725, 30275.0, 30498.54252186219, 30762.53856192159, 30764.25611509356, 1051.8001579699833, 1121.1367247219596, 1167.3754541320352, 1184.9631572374174, 1246.1837496439846, 14921.328540215716, 15841.68316633684, 16920.323187072627, 17506.912465437847, 17518.474445766646, 441676.0, 445830.0, 448666.0, 449088.0, 450315.0, 63881.68059159674, 64252.0, 64364.67817660882, 64421.35578644247, 64558.0, 1364.747521708487, 1392.373431955624, 1461.0, 1482.577465422371, 1501.0768377447791, 30234.84882575661, 30627.846860765552, 30824.69175308308, 31315.0, 32120.67879321177, 50198.49801502084, 50587.0, 50845.49154508408, 50860.74569627128, 50938.49061509486, 291244.0, 292578.0, 293749.0, 293849.0, 294129.0, 36427.07885501746, 37620.435693464075, 37754.811225944795, 37835.432468512445, 37863.62136378601, 48108.0, 49098.0, 49115.0, 49375.0, 49716.0, 40627.0, 41143.7942810296, 41174.38238426366, 41524.79237603793, 42355.576444236394, 39839.0, 40456.59543404528, 40636.79681601573, 40687.09969818641, 41103.17793644051, 34354.141146471535, 34534.30931381408, 35036.474452882714, 35056.0, 35409.0, 35977.46033809547, 36470.0, 37747.62252377441, 40839.0, 41951.370729439695, 27312.590311145745, 27542.17373478799, 27719.86140069287, 27781.0, 27785.86107069452], "unit": "ms", "name": "ext.html", "value": 20901.704442615657}, {"lowerIsBetter": false, "replicates": [357.03956357398596, 359.02524645587226, 364.8234443918362, 376.92838360711465, 381.769029737009, 13217.0, 16119.597010074725, 18379.72430413545, 20526.486837829045, 21321.57356852862, 20085.698714519294, 20089.497762555944, 20457.488562785937, 20465.897670511666, 20490.2828401006, 5769.740361683724, 5900.911486327708, 5902.3507414184405, 5923.644581325113, 5925.822225333247, 4354.1074079813725, 4521.502634710179, 5803.448672376117, 6292.622442653455, 6496.058071579618, 6354.237491501026, 6478.870422591545, 6504.349565043505, 6520.380563846427, 6535.673216339188, 6171.567990240685, 6183.2889217740085, 6201.720922558473, 6211.285702144243, 6213.130161777355, 5574.749136288877, 5586.804461843816, 5591.077472217079, 5598.580106491989, 5845.7369418376275, 23862.52274954491, 24617.753822461724, 24813.0, 24854.751452485423, 25031.749682503123, 2285.988570057156, 2289.2216646340157, 2353.1646265575646, 2356.328446392787, 2442.46265821519, 14397.136171829723, 14510.274486275644, 14518.564443066725, 14610.790414073976, 14680.412783488755, 1768.478298901827, 1803.2877013579694, 1824.7262910563522, 1839.5768973136253, 1888.8383644058943, 72851.63574182095, 72868.27131728668, 75171.24828751697, 75248.0, 75285.0, 11544.59593914209, 11898.286102833858, 11996.880031199751, 11999.820002699966, 12014.159008869296, 16202.513924582281, 16214.270357833926, 16265.918670406572, 16266.268017939221, 16270.430534931458, 8455.577221138918, 8491.532965686947, 8504.14958504157, 8617.396782225183, 8666.436681615674, 8063.677452901936, 8197.221263979913, 8205.753827385188, 8260.876086858701, 8287.62705678246, 6866.862662746717, 6964.477664175207, 6973.267806880306, 7001.0, 7001.9147032210085, 1741.9839892568302, 1994.262123014494, 2049.7437820272467, 2072.2332736887447, 2074.543600407938, 494.90101979604225, 504.14295697314714, 536.5680627095226, 556.0824639345113, 576.3026737647493, 11454.427278636034, 12841.93579032099, 12906.320259805147, 13297.933510332387, 13539.729205416033, 530.4776777967855, 540.2922171954751, 547.1874266713927, 562.8170844475562, 576.4437317988078, 6355.3644635536075, 6748.797536073926, 7242.0, 7394.519356241827, 7433.182349941502, 631685.0, 648897.0, 652373.0, 674887.0, 680982.0, 84790.57604711936, 86392.0, 87031.1296887023, 88599.0, 91735.0, 593.4273426143792, 593.4984937727527, 596.558546675457, 596.9582129250998, 601.8736065426222, 11997.940010299893, 12139.210951288138, 12159.270443773403, 12195.085368597212, 12218.511259549874, 37042.07394815151, 37466.43800342942, 37547.43678844765, 39039.0, 39066.0, 51977.22034169415, 53066.469335307695, 53302.93394132018, 53341.19988200103, 54017.729911350194, 7530.585817780075, 7936.722214722457, 7940.801479963047, 7946.642401091849, 7947.682092716226, 13863.376148073563, 13961.301934903417, 14046.648833779238, 14147.858521415066, 14539.781903271249, 12223.266604003788, 12352.197107188003, 12380.133390662928, 12540.749185016066, 12611.243325400139, 11975.820362694392, 12310.261384316611, 12362.876371236533, 12481.56314528987, 12641.93679031599, 12370.505179792708, 12407.25556466615, 12504.124711270124, 12558.246505209716, 12568.937155314165, 7769.72805951789, 7833.295003449489, 7906.802329941797, 7909.446338756232, 7920.0, 11779.705507362385, 11958.342291174069, 11996.16026878145, 12028.09789265791, 12219.633410997685], "unit": "ms", "name": "jquery.html", "value": 8785.756838434248}, {"lowerIsBetter": false, "replicates": [1540.2991638804344, 1552.9922350388247, 1750.0, 1795.4523870219582, 1823.6990896502073, 7649.92350076499, 7932.881006784888, 7935.88096178557, 7939.642716077776, 7947.403944704141, 7265.346118849303, 7416.406687465003, 7880.172581878906, 7890.092639346477, 8093.190680931903, 2324.2910912171783, 2332.4985128197436, 2359.976400235993, 2363.2555744940287, 2365.988170059156, 2114.9154033838627, 2388.737238903719, 2403.831731778776, 2437.122635851092, 2442.5725498037773, 1992.4919145617848, 2323.674685544025, 2373.7507561705943, 2374.133441293924, 2374.3114496795906, 2341.2039906431896, 2356.9410764730846, 2372.2764556810166, 2373.560891235127, 2378.0725517048363, 2324.8140148788057, 2330.906763729449, 2351.8588884666974, 2357.799587035104, 2358.681577986967, 8120.472169308976, 8186.304164146055, 8188.631511581933, 8189.181081891823, 8197.79505512361, 5023.2214006829, 5110.28456016158, 5110.846674599731, 5111.284420181178, 5111.539961403491, 3195.808251504894, 3213.260949981494, 3234.6118465784257, 3290.835458227079, 3309.238875058726, 1060.7454210989306, 1907.4711783200967, 1922.9326973555999, 1927.363969889933, 1941.1167918597005, 11245.662630121109, 11399.316041037564, 11403.771924561463, 11406.486708098155, 11422.771544569145, 4376.627986621109, 4398.538153493901, 4444.866654000385, 4466.553344665507, 4467.8212871484775, 9168.954155229181, 9215.815683686289, 9231.90768092324, 9259.629614815332, 9277.675281365255, 5009.724465154379, 5009.949900501021, 5015.0, 5024.874378140576, 5029.949700502948, 4666.416697912761, 4667.906641867144, 4678.1813182692895, 4685.086408150378, 4688.64835137366, 3571.0, 3595.96987888677, 3596.0650230940146, 3621.27574485104, 3625.963740362615, 1036.9014943580348, 1069.5294070608907, 1070.3203465799118, 1101.9063379612824, 1112.0991996482874, 1088.4992903264429, 1131.0499180688262, 1138.732397886496, 1142.662914440242, 1191.642507247822, 7672.923270767333, 7827.295543401121, 7858.489198202098, 7916.525008499509, 7919.920800792033, 1116.547798141754, 1158.2645020412099, 1199.4362649554696, 1214.7752665756864, 1218.780619488483, 6283.901036243758, 6336.239651241881, 6348.682565871688, 6378.425941665179, 6383.3616638336725, 9829.803403932025, 9972.850407243897, 10001.799964000826, 10003.44981026036, 10015.298929074896, 9798.0, 9912.405255684538, 9994.450305233282, 10013.198944084601, 10019.549120289605, 7722.266384693445, 7765.844683106307, 7794.727184548514, 7797.025371828521, 7816.648250828613, 7404.037475128414, 7472.103347598323, 7501.924980750123, 7537.321641052332, 7549.0, 8696.347773917108, 8831.690890819047, 8961.059088795582, 8967.910320896708, 9005.594748236213, 8922.375433719837, 8975.147361000694, 8990.505522196341, 9001.234895033995, 9202.125798049443, 7031.22656507794, 7112.324329188718, 7123.109611298588, 7123.180834204133, 7130.2156762757095, 7715.074191097105, 7816.4137689674635, 7879.763607091796, 7889.684412623431, 7906.52560846351, 7501.32488076054, 7524.699012039458, 7535.208803075818, 7560.3573696236435, 7656.272654097852, 6171.551771464049, 6525.510586706111, 6725.865482690221, 6933.1333583302085, 7102.857942841218, 6960.895586566105, 6991.825204369931, 6992.895106573304, 6995.615241161785, 6998.930010700032, 6742.764003259862, 6786.355296246848, 6894.207166175754, 6929.861402772017, 6944.930550694428, 7042.260562640849, 7069.752558660422, 7071.82320441993, 7073.292670732885, 7076.964615176891], "unit": "ms", "name": "mootools.html", "value": 4741.518482456782}, {"lowerIsBetter": false, "replicates": [351.1204432895596, 354.73040489228185, 356.8251556737198, 357.93020361029596, 359.69066602721654, 5877.471027607515, 5882.735276912533, 5884.205632239647, 5889.587728858982, 5932.1991531143285, 5823.621464604808, 5826.067829147337, 5833.095870140124, 5834.941650583492, 5835.649861008343, 4529.162105010567, 4555.840545580905, 4578.839740609079, 4598.908021839561, 4660.440747110351, 4615.8846028849375, 4640.280756482751, 4652.883677908046, 4664.183767840631, 4672.55610716983, 4587.311903214511, 4597.494275629678, 4598.195315819735, 4599.517050709677, 4610.101030299093, 4411.933820992687, 4472.709273897202, 4599.885002874938, 4625.398698169251, 4637.976810115928, 4110.671146308288, 4119.8352065917325, 4121.175764847043, 4127.4015267786, 4139.855105071339, 5481.917771233435, 5560.193771903051, 5615.550755939515, 5625.296837895263, 5649.350324712628, 5329.440408757102, 5381.408045115034, 5407.83776486706, 5411.918821217685, 5412.404635490093, 6195.845103872394, 6231.376862313778, 6244.531660125509, 6255.593386429868, 6264.718087686065, 4562.908741825145, 4563.703359281636, 4564.155631208204, 4564.885877853047, 4573.49691533931, 6222.408871157233, 6412.230532336102, 6434.871302573969, 6499.642519661416, 6504.902426463606, 4701.976490117596, 4725.1730947084125, 4725.314829349793, 4725.69282996611, 4747.382840230817, 4800.159972004885, 4803.807847686054, 4806.399200099988, 4807.711537307773, 4808.735519546389, 5293.550048245942, 5413.702246376487, 5429.131338985743, 5438.564914806806, 5444.3194600674915, 6297.1813664223355, 6303.716332765037, 6303.905441418382, 6305.14880491137, 6305.716242769086, 1717.828217178272, 1726.0, 1728.7925448945955, 1736.2881218700272, 1736.3054778088613, 1179.0803173524664, 1180.0677464802882, 1182.4501606752729, 1186.063010221915, 1212.2665787198794, 6106.603070800384, 6122.051082082285, 6123.877522449526, 6128.908066379008, 6133.355997620275, 6525.510586706016, 6533.150690410216, 6534.085228068075, 6535.052417399451, 6536.281009088996, 5989.970050149808, 6075.210222671025, 6104.38956104386, 6115.449609535163, 6117.327094019654, 3585.8027808470265, 3610.711143108545, 3618.656227658368, 3619.4570814378053, 3621.9637803621627, 6684.0, 6724.293949135368, 6740.460763138937, 6744.831379215559, 6745.730170793211, 6485.0272459130565, 6565.868682626417, 6596.307387724316, 6679.56582822115, 6706.698198581075, 6684.532082754258, 6709.865802683919, 6712.53012289135, 6732.798016059526, 6741.662916854236, 6443.065755465525, 6469.482441404771, 6484.643344616092, 6487.18910136233, 6495.188101487314, 6300.338464461165, 6487.156669632917, 6503.187101612299, 6506.3818937200895, 6509.544331896722, 6808.863822723617, 6811.284815094542, 6815.079964204769, 6817.113775209191, 6819.0, 4745.430548334222, 4753.714777113384, 4758.381410416624, 4760.78576464053, 4761.500042495488, 6481.902771458337, 6605.702743376656, 6609.735610575523, 6612.206535215805, 6613.338666133541, 6442.935570644234, 6443.516736244706, 6449.580777249464, 6452.12896258999, 6458.418742313214, 6428.453581445629, 6429.839254018687, 6431.099646049463, 6433.646149461824, 6434.163558737334, 6224.50355265069, 6297.118403423433, 6324.747010119544, 6326.2092238470195, 6338.207724034496, 6410.679466026773, 6456.451201647725, 6462.127612772216, 6462.386073323027, 6464.062710906798, 6299.779507717208, 6309.779157729457, 6312.40032196959, 6312.936870631235, 6313.05304204373], "unit": "ms", "name": "prototype.html", "value": 4851.383850495687}, {"lowerIsBetter": false, "replicates": [863.6027427383403, 935.0836180543067, 949.8290307744612, 954.0888451528789, 958.5111593087523, 15355.923220383896, 15529.767053494192, 15610.219489025532, 15631.531054068368, 15665.530034098967, 14723.705525889476, 14773.0, 14783.334749936252, 14795.33420996055, 14823.629409264773, 873.6112429968666, 894.838928992781, 899.4872922434215, 899.6626265150569, 900.5587262241487, 900.522722956834, 903.5166186090443, 905.1129892705153, 905.755446029575, 907.2787134228304, 938.8544775559765, 941.948192849393, 946.9952650236739, 947.0387556630026, 947.2895328503622, 938.502593625383, 939.9060093990614, 940.3041749105613, 941.3975055964188, 941.472775245861, 684.0594182998377, 687.3023880761032, 687.5324779150176, 687.7180356053993, 688.1810645332039, 15177.6205594861, 15209.695806083817, 15252.923735381362, 15253.0, 15384.384624615002, 11311.264767790068, 11355.602553910574, 11397.430128493543, 11406.372649504274, 11605.0, 10434.269601127924, 10453.84319235212, 10569.577216911315, 10610.946945265225, 11504.194706370557, 3421.298633780069, 3451.3097380523996, 3452.499387588811, 3460.584729832436, 3519.3137338219058, 43159.136817263796, 43476.130477390274, 48637.75681121607, 49173.75413122947, 49726.50273497255, 8219.671213151407, 8458.40791144614, 8461.23848853606, 8473.661053557911, 8798.9881163667, 13615.183089014492, 13630.522931697542, 13635.727285454235, 13646.044776865525, 13752.724945501033, 7270.491065625355, 7277.163126240444, 7344.669489872968, 7406.666699998513, 7653.57905315213, 4507.5267096955, 4564.977175114103, 4585.082983403266, 4717.150912835723, 4749.050189962021, 2982.8508574571183, 3006.038067818321, 3012.0662594595747, 3014.954775678367, 3017.0345489443166, 466.0981001761602, 483.2316616579655, 490.2156549520738, 491.42257847029595, 491.52322247419943, 541.1978112884086, 575.1085816983742, 592.1946153231602, 614.649649699667, 639.6737663791407, 8013.559254240956, 8036.397270204758, 8161.79595510117, 8323.66705331792, 8644.524551149747, 511.88482591416636, 605.9214598015451, 614.8611339113849, 637.8660481298887, 658.3712554510431, 4040.4949381327333, 4042.2319759245647, 4103.630673239363, 4115.508063836058, 4139.606737360006, 69339.30660693429, 70125.64937175282, 70128.0, 73902.0, 74644.0, 58275.41724582785, 59067.40932590705, 59197.0, 59487.40512594905, 60470.697646511486, 45740.5425945743, 49169.0, 56541.71729141328, 57173.71413142908, 57201.71399143061, 8428.662853485792, 8546.059933407196, 8612.61243244043, 8618.655253789779, 8625.39622226438, 40489.79755101206, 40649.0, 41796.79101604473, 41978.37032444455, 42779.35830962476, 50929.745351273006, 53928.730356347965, 54065.0, 54550.454495454534, 54771.45228547663, 8371.497710137412, 8793.428427152214, 8880.28957683371, 8979.281657467256, 9120.5439728012, 13352.732945341233, 13400.396982136022, 13508.932455337661, 13514.526991555249, 13747.793783093062, 10988.560457581607, 11096.5561377544, 11414.48634811419, 11600.6519804406, 11955.701107472383, 12432.564860229848, 12447.93776031114, 12525.123241373016, 12551.0, 12823.807642885551, 11470.369129697947, 11806.468708907947, 11813.232139910879, 12011.939940300592, 12120.18417731291, 10682.679519614425, 10758.13934885223, 10767.696909272345, 10961.397123158304, 11023.0630396414, 10239.7440063999, 10456.268061235945, 10473.423961681876, 10474.581016759244, 10508.21188410857], "unit": "ms", "name": "yui.html", "value": 6533.84854164428}], "name": "dromaeo_css", "value": 7336.407414948087}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 112.0, 104.0, 114.0, 110.0, 111.0, 100.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 109.0}, {"lowerIsBetter": true, "replicates": [147.0, 122.0, 144.0, 161.0, 129.0, 146.0, 147.0, 124.0, 168.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.1}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 159.0, 161.0, 160.0, 160.0, 160.0, 162.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 72.0, 139.0, 72.0, 72.0, 72.0, 72.0, 153.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 86.4}, {"lowerIsBetter": true, "replicates": [88.0, 88.0, 143.0, 88.0, 89.0, 89.0, 137.0, 88.0, 89.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 103.1}, {"lowerIsBetter": true, "replicates": [110.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.2}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [55.0, 54.0, 53.0, 55.0, 53.0, 54.0, 54.0, 55.0, 52.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 53.8}, {"lowerIsBetter": true, "replicates": [61.0, 62.0, 61.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 61.3}, {"lowerIsBetter": true, "replicates": [76.0, 79.0, 78.0, 78.0, 79.0, 77.0, 77.0, 79.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.8}, {"lowerIsBetter": true, "replicates": [111.0, 116.0, 111.0, 107.0, 109.0, 109.0, 112.0, 122.0, 109.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.1}, {"lowerIsBetter": true, "replicates": [137.0, 137.0, 138.0, 139.0, 137.0, 137.0, 148.0, 135.0, 136.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.4}, {"lowerIsBetter": true, "replicates": [60.0, 61.0, 61.0, 61.0, 62.0, 62.0, 63.0, 61.0, 60.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.5}], "name": "kraken", "value": 1453.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.20600162206003], "unit": "score", "name": "Richards", "value": 217.63682999999997}, {"lowerIsBetter": false, "replicates": [169.4915254237288], "unit": "score", "name": "DeltaBlue", "value": 390.0962}, {"lowerIsBetter": false, "replicates": [280.3476310625175], "unit": "score", "name": "Encrypt", "value": 949.4676269999999}, {"lowerIsBetter": false, "replicates": [5025.125628140703], "unit": "score", "name": "Decrypt", "value": 52.970019}, {"lowerIsBetter": false, "replicates": [948.7666034155598], "unit": "score", "name": "RayTrace", "value": 779.9484060000001}, {"lowerIsBetter": false, "replicates": [555.247084952804], "unit": "score", "name": "Earley", "value": 1200.2998630000004}, {"lowerIsBetter": false, "replicates": [10453.60824742268], "unit": "score", "name": "Boyer", "value": 63.7543500986193}, {"lowerIsBetter": false, "replicates": [24119.04761904762], "unit": "score", "name": "RegExp", "value": 37.77035538005923}, {"lowerIsBetter": false, "replicates": [530.2226935312831], "unit": "score", "name": "Splay", "value": 153.69202600000006}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23310.336220806374}]} 15:18:36 INFO - RETURN: dromaeo_css: 7336.41 15:18:36 INFO - RETURN: kraken: 1453.80 15:18:36 INFO - RETURN: v8_7: 23310.34 15:18:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23310.34"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1453.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7336.41"}}} 15:18:36 INFO - Return code: 0 15:18:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 15:18:36 INFO - # TBPL SUCCESS # 15:18:36 INFO - Running post-action listener: _resource_record_post_action 15:18:36 INFO - Running post-run listener: _resource_record_post_run 15:18:37 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 319488; Write bytes: 507854848; Read time: 104; Write time: 1126060 15:18:37 INFO - install - Wall time: 16s; CPU: 19.0%; Read bytes: 0; Write bytes: 247230464; Read time: 0; Write time: 762716 15:18:37 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 319488; Write bytes: 260624384; Read time: 104; Write time: 363344 15:18:37 INFO - Running post-run listener: _upload_blobber_files 15:18:37 INFO - Blob upload gear active. 15:18:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:18:37 INFO - Copying logs to upload dir... 15:18:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1260.404898 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2015-12-23 15:18:37.372635) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-23 15:18:37.375979) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.008583 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450902870/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-23 15:18:37.424271) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 15:18:37.424614) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450911455.343550-1219504354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004614 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 15:18:37.474496) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-23 15:18:37.474884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-23 15:18:37.475271) ========= ========= Total master_lag: 0.24 =========