builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1450231777.71 results: success (0) buildid: 20151214205110 builduid: 489f3942ee5c444ea53c5ff4fc03e011 revision: d1a4b331d80b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.711337) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.711769) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.712072) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006422 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.736939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.737295) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.763701) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.763983) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004683 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.812943) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.813340) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.813818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.814123) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-15 18:09:38-- 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% 40.1M=0s 2015-12-15 18:09:38 (40.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.964959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:37.965271) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022387 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:38.017050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 18:09:38.017416) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1a4b331d80b --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1a4b331d80b --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-15 18:09:38,361 Setting DEBUG logging. 2015-12-15 18:09:38,361 attempt 1/10 2015-12-15 18:09:38,361 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d1a4b331d80b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-15 18:09:39,402 unpacking tar archive at: mozilla-beta-d1a4b331d80b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.310947 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 18:09:39.341187) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:39.341504) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:39.351426) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:39.351703) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 18:09:39.352120) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2015-12-15 18:09:39.352541) ========= /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=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:09:39 INFO - MultiFileLogger online at 20151215 18:09:39 in /builds/slave/test-pgo 18:09:39 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 18:09:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:09:39 INFO - {'append_to_log': False, 18:09:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:09:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:09:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:09:39 INFO - 'branch': 'Mozilla-Beta', 18:09:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:09:39 INFO - 'config_files': ('talos/linux_config.py',), 18:09:39 INFO - 'default_actions': ('clobber', 18:09:39 INFO - 'read-buildbot-config', 18:09:39 INFO - 'download-and-extract', 18:09:39 INFO - 'populate-webroot', 18:09:39 INFO - 'create-virtualenv', 18:09:39 INFO - 'install', 18:09:39 INFO - 'run-tests'), 18:09:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:09:39 INFO - 'download_minidump_stackwalk': True, 18:09:39 INFO - 'download_symbols': 'ondemand', 18:09:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:09:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:09:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:09:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:09:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:09:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:09:39 INFO - 'installer_path': 'installer.exe', 18:09:39 INFO - 'log_level': 'info', 18:09:39 INFO - 'log_name': 'talos', 18:09:39 INFO - 'log_to_console': True, 18:09:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:09:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:09:39 INFO - 'opt_config_files': (), 18:09:39 INFO - 'pip_index': False, 18:09:39 INFO - 'sps_profile': False, 18:09:39 INFO - 'sps_profile_interval': 0, 18:09:39 INFO - 'suite': 'dromaeojs', 18:09:39 INFO - 'system_bits': '64', 18:09:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:09:39 INFO - 'title': 'talos-linux64-ix-068', 18:09:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:09:39 INFO - 'use_talos_json': True, 18:09:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:09:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:09:39 INFO - 'work_dir': 'build'} 18:09:39 INFO - ##### 18:09:39 INFO - ##### Running clobber step. 18:09:39 INFO - ##### 18:09:39 INFO - Running pre-action listener: _resource_record_pre_action 18:09:39 INFO - Running main action method: clobber 18:09:39 INFO - rmtree: /builds/slave/test-pgo/build 18:09:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:09:40 INFO - Running post-action listener: _resource_record_post_action 18:09:40 INFO - ##### 18:09:40 INFO - ##### Running read-buildbot-config step. 18:09:40 INFO - ##### 18:09:40 INFO - Running pre-action listener: _resource_record_pre_action 18:09:40 INFO - Running main action method: read_buildbot_config 18:09:40 INFO - Using buildbot properties: 18:09:40 INFO - { 18:09:40 INFO - "properties": { 18:09:40 INFO - "buildnumber": 71, 18:09:40 INFO - "product": "firefox", 18:09:40 INFO - "script_repo_revision": "production", 18:09:40 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:09:40 INFO - "repository": "", 18:09:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:09:40 INFO - "buildid": "20151214205110", 18:09:40 INFO - "slavename": "talos-linux64-ix-068", 18:09:40 INFO - "pgo_build": "True", 18:09:40 INFO - "basedir": "/builds/slave/test-pgo", 18:09:40 INFO - "project": "", 18:09:40 INFO - "platform": "ubuntu64_hw", 18:09:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:09:40 INFO - "slavebuilddir": "test", 18:09:40 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:09:40 INFO - "branch": "mozilla-beta", 18:09:40 INFO - "repo_path": "releases/mozilla-beta", 18:09:40 INFO - "stage_platform": "linux64-pgo", 18:09:40 INFO - "builduid": "489f3942ee5c444ea53c5ff4fc03e011", 18:09:40 INFO - "revision": "d1a4b331d80b" 18:09:40 INFO - }, 18:09:40 INFO - "sourcestamp": { 18:09:40 INFO - "repository": "", 18:09:40 INFO - "hasPatch": false, 18:09:40 INFO - "project": "", 18:09:40 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:09:40 INFO - "changes": [ 18:09:40 INFO - { 18:09:40 INFO - "category": null, 18:09:40 INFO - "files": [ 18:09:40 INFO - { 18:09:40 INFO - "url": null, 18:09:40 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2" 18:09:40 INFO - } 18:09:40 INFO - ], 18:09:40 INFO - "repository": "", 18:09:40 INFO - "rev": "d1a4b331d80b", 18:09:40 INFO - "who": "sendchange", 18:09:40 INFO - "when": 1450231719, 18:09:40 INFO - "number": 7364567, 18:09:40 INFO - "comments": "Bug 1232466 - Hack around use of re.sub(..., flags=). r=me a=bustage", 18:09:40 INFO - "project": "", 18:09:40 INFO - "at": "Tue 15 Dec 2015 18:08:39", 18:09:40 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:09:40 INFO - "revlink": "", 18:09:40 INFO - "properties": [ 18:09:40 INFO - [ 18:09:40 INFO - "buildid", 18:09:40 INFO - "20151214205110", 18:09:40 INFO - "Change" 18:09:40 INFO - ], 18:09:40 INFO - [ 18:09:40 INFO - "builduid", 18:09:40 INFO - "489f3942ee5c444ea53c5ff4fc03e011", 18:09:40 INFO - "Change" 18:09:40 INFO - ], 18:09:40 INFO - [ 18:09:40 INFO - "pgo_build", 18:09:40 INFO - "True", 18:09:40 INFO - "Change" 18:09:40 INFO - ] 18:09:40 INFO - ], 18:09:40 INFO - "revision": "d1a4b331d80b" 18:09:40 INFO - } 18:09:40 INFO - ], 18:09:40 INFO - "revision": "d1a4b331d80b" 18:09:40 INFO - } 18:09:40 INFO - } 18:09:40 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2. 18:09:40 INFO - Running post-action listener: _resource_record_post_action 18:09:40 INFO - ##### 18:09:40 INFO - ##### Running download-and-extract step. 18:09:40 INFO - ##### 18:09:40 INFO - Running pre-action listener: _resource_record_pre_action 18:09:40 INFO - Running main action method: download_and_extract 18:09:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:09: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')]} 18:09:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json 18:09:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:09:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:09:40 INFO - Downloaded 1270 bytes. 18:09:40 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:09:40 INFO - Using the following test package requirements: 18:09:40 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:09:40 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:09:40 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:09:40 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:09:40 INFO - u'jsshell-linux-x86_64.zip'], 18:09:40 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:09:40 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:09:40 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:09:40 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:09:40 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 18:09:40 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:09:40 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 18:09:40 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:09:40 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:09:40 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:09:40 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:09:40 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:09:40 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:09: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')]} 18:09:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:09:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:09:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:09:41 INFO - Downloaded 22571345 bytes. 18:09:41 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 18:09:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:09:41 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 18:09:42 INFO - Return code: 0 18:09:42 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 18:09: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')]} 18:09:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:09:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:09:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:09:42 INFO - Downloaded 22571345 bytes. 18:09:42 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 18:09:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:09:42 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 18:09:44 INFO - Return code: 0 18:09: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')]} 18:09:44 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:09:44 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:09:44 INFO - Downloaded 11131970 bytes. 18:09: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 18:09:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:09: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 18:09:44 INFO - Return code: 0 18:09: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')]} 18:09:44 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:09:44 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:09:45 INFO - Downloaded 56636614 bytes. 18:09:45 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:09:45 INFO - mkdir: /builds/slave/test-pgo/properties 18:09:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:09:45 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:09:45 INFO - Contents: 18:09:45 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:09:45 INFO - Running post-action listener: _resource_record_post_action 18:09:45 INFO - Running post-action listener: set_extra_try_arguments 18:09:45 INFO - ##### 18:09:45 INFO - ##### Running populate-webroot step. 18:09:45 INFO - ##### 18:09:45 INFO - Running pre-action listener: _resource_record_pre_action 18:09:45 INFO - Running main action method: populate_webroot 18:09:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:09:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:09:45 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:09:45 INFO - '../fennec_ids.txt'], 18:09:45 INFO - 'tests': ['tcheck2']}, 18:09:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:09:45 INFO - '--tppagecycles', 18:09:45 INFO - '7'], 18:09:45 INFO - 'tests': ['tsvgm']}}, 18:09:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:09:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:09:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:09:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:09:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:09:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:09:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'tests': ['damp', 'tps']}, 18:09:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['damp', 'tps']}, 18:09:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:09:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:09:45 INFO - 'other': {'tests': ['a11yr', 18:09:45 INFO - 'ts_paint', 18:09:45 INFO - 'tpaint', 18:09:45 INFO - 'sessionrestore', 18:09:45 INFO - 'sessionrestore_no_auto_restore']}, 18:09:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['a11yr', 18:09:45 INFO - 'ts_paint', 18:09:45 INFO - 'tpaint', 18:09:45 INFO - 'sessionrestore', 18:09:45 INFO - 'sessionrestore_no_auto_restore']}, 18:09:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['a11yr', 18:09:45 INFO - 'ts_paint', 18:09:45 INFO - 'tpaint', 18:09:45 INFO - 'sessionrestore', 18:09:45 INFO - 'sessionrestore_no_auto_restore']}, 18:09:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['a11yr', 18:09:45 INFO - 'ts_paint', 18:09:45 INFO - 'tpaint', 18:09:45 INFO - 'sessionrestore', 18:09:45 INFO - 'sessionrestore_no_auto_restore']}, 18:09:45 INFO - 'other_l64': {'tests': ['a11yr', 18:09:45 INFO - 'ts_paint', 18:09:45 INFO - 'tpaint', 18:09:45 INFO - 'sessionrestore', 18:09:45 INFO - 'sessionrestore_no_auto_restore']}, 18:09:45 INFO - 'other_nol64': {'tests': ['a11yr', 18:09:45 INFO - 'ts_paint', 18:09:45 INFO - 'tpaint', 18:09:45 INFO - 'sessionrestore', 18:09:45 INFO - 'sessionrestore_no_auto_restore']}, 18:09:45 INFO - 'svgr': {'tests': ['tsvgx', 18:09:45 INFO - 'tsvgr_opacity', 18:09:45 INFO - 'tart', 18:09:45 INFO - 'tscrollx', 18:09:45 INFO - 'cart']}, 18:09:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['tsvgx', 18:09:45 INFO - 'tsvgr_opacity', 18:09:45 INFO - 'tart', 18:09:45 INFO - 'tscrollx', 18:09:45 INFO - 'cart']}, 18:09:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'tests': ['tp5o']}, 18:09:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'talos_options': ['--e10s'], 18:09:45 INFO - 'tests': ['tp5o']}, 18:09:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'talos_options': ['--xperf_path', 18:09:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:09:45 INFO - 'tests': ['tp5n']}, 18:09:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:45 INFO - 'talos_options': ['--e10s', 18:09:45 INFO - '--xperf_path', 18:09:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:09:45 INFO - 'tests': ['tp5n']}}, 18:09:45 INFO - 'talos.zip': {'path': '', 18:09:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:09:45 INFO - Running post-action listener: _resource_record_post_action 18:09:45 INFO - ##### 18:09:45 INFO - ##### Running create-virtualenv step. 18:09:45 INFO - ##### 18:09:45 INFO - Running pre-action listener: _resource_record_pre_action 18:09:45 INFO - Running main action method: create_virtualenv 18:09:45 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:09:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 18:09:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:09:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:09:45 INFO - Using real prefix '/usr' 18:09:45 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:09:46 INFO - Installing distribute.............................................................................................................................................................................................done. 18:09:47 INFO - Installing pip.................done. 18:09:47 INFO - Return code: 0 18:09:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:09:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:09:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:09:47 INFO - Using env: {'DISPLAY': ':0', 18:09:47 INFO - 'HOME': '/home/cltbld', 18:09:47 INFO - 'LANG': 'en_US.UTF-8', 18:09:47 INFO - 'LANGUAGE': 'en_US:en', 18:09:47 INFO - 'LOGNAME': 'cltbld', 18:09:47 INFO - 'MAIL': '/var/mail/cltbld', 18:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:47 INFO - 'MOZ_NO_REMOTE': '1', 18:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:47 INFO - 'NO_EM_RESTART': '1', 18:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:09:47 INFO - 'PWD': '/builds/slave/test-pgo', 18:09:47 INFO - 'SHELL': '/bin/bash', 18:09:47 INFO - 'SHLVL': '1', 18:09:47 INFO - 'TERM': 'linux', 18:09:47 INFO - 'TMOUT': '86400', 18:09:47 INFO - 'USER': 'cltbld', 18:09:47 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:47 INFO - '_': '/tools/buildbot/bin/python'} 18:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:48 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 18:09:48 INFO - Cleaning up... 18:09:48 INFO - Return code: 0 18:09:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:09:48 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')]} 18:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:48 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')]} 18:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:48 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 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:09:48 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 18:09:48 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 18:09:48 INFO - Using env: {'DISPLAY': ':0', 18:09:48 INFO - 'HOME': '/home/cltbld', 18:09:48 INFO - 'LANG': 'en_US.UTF-8', 18:09:48 INFO - 'LANGUAGE': 'en_US:en', 18:09:48 INFO - 'LOGNAME': 'cltbld', 18:09:48 INFO - 'MAIL': '/var/mail/cltbld', 18:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:48 INFO - 'MOZ_NO_REMOTE': '1', 18:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:48 INFO - 'NO_EM_RESTART': '1', 18:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:09:48 INFO - 'PWD': '/builds/slave/test-pgo', 18:09:48 INFO - 'SHELL': '/bin/bash', 18:09:48 INFO - 'SHLVL': '1', 18:09:48 INFO - 'TERM': 'linux', 18:09:48 INFO - 'TMOUT': '86400', 18:09:48 INFO - 'USER': 'cltbld', 18:09:48 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:48 INFO - '_': '/tools/buildbot/bin/python'} 18:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:48 INFO - Downloading/unpacking psutil>=0.7.1 18:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:50 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:09:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:09:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:09:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:50 INFO - Installing collected packages: psutil 18:09:50 INFO - Running setup.py install for psutil 18:09:50 INFO - building 'psutil._psutil_linux' extension 18:09:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:09:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:09:50 INFO - building 'psutil._psutil_posix' extension 18:09:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:09:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:09:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:50 INFO - Successfully installed psutil 18:09:50 INFO - Cleaning up... 18:09:50 INFO - Return code: 0 18:09:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:09: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')]} 18:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09: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')]} 18:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:09:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:09:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:09:50 INFO - Using env: {'DISPLAY': ':0', 18:09:50 INFO - 'HOME': '/home/cltbld', 18:09:50 INFO - 'LANG': 'en_US.UTF-8', 18:09:50 INFO - 'LANGUAGE': 'en_US:en', 18:09:50 INFO - 'LOGNAME': 'cltbld', 18:09:50 INFO - 'MAIL': '/var/mail/cltbld', 18:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:50 INFO - 'MOZ_NO_REMOTE': '1', 18:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:50 INFO - 'NO_EM_RESTART': '1', 18:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:09:50 INFO - 'PWD': '/builds/slave/test-pgo', 18:09:50 INFO - 'SHELL': '/bin/bash', 18:09:50 INFO - 'SHLVL': '1', 18:09:50 INFO - 'TERM': 'linux', 18:09:50 INFO - 'TMOUT': '86400', 18:09:50 INFO - 'USER': 'cltbld', 18:09:50 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:50 INFO - '_': '/tools/buildbot/bin/python'} 18:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:09:51 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 18:09:51 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 18:09:51 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 18:09:51 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 18:09:51 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 18:09:51 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 18:09:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:09:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:09:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:09:52 INFO - Installing collected packages: mozsystemmonitor 18:09:52 INFO - Running setup.py install for mozsystemmonitor 18:09:52 INFO - Successfully installed mozsystemmonitor 18:09:52 INFO - Cleaning up... 18:09:52 INFO - Return code: 0 18:09:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:09:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 18:09:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:09:52 INFO - Using env: {'DISPLAY': ':0', 18:09:52 INFO - 'HOME': '/home/cltbld', 18:09:52 INFO - 'LANG': 'en_US.UTF-8', 18:09:52 INFO - 'LANGUAGE': 'en_US:en', 18:09:52 INFO - 'LOGNAME': 'cltbld', 18:09:52 INFO - 'MAIL': '/var/mail/cltbld', 18:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:52 INFO - 'MOZ_NO_REMOTE': '1', 18:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:52 INFO - 'NO_EM_RESTART': '1', 18:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:09:52 INFO - 'PWD': '/builds/slave/test-pgo', 18:09:52 INFO - 'SHELL': '/bin/bash', 18:09:52 INFO - 'SHLVL': '1', 18:09:52 INFO - 'TERM': 'linux', 18:09:52 INFO - 'TMOUT': '86400', 18:09:52 INFO - 'USER': 'cltbld', 18:09:52 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:52 INFO - '_': '/tools/buildbot/bin/python'} 18:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:53 INFO - Downloading/unpacking blobuploader==1.2.4 18:09: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 18:09: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 18:09: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 18:09: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 18:09: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 18:09: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 18:09:54 INFO - Downloading blobuploader-1.2.4.tar.gz 18:09:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:09:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:09:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:09:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:09:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:09: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 18:09: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 18:09: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 18:09: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 18:09: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 18:09: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 18:09:55 INFO - Downloading docopt-0.6.1.tar.gz 18:09:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:09:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:09:55 INFO - Installing collected packages: blobuploader, requests, docopt 18:09:55 INFO - Running setup.py install for blobuploader 18:09:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:09:55 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:09:55 INFO - Running setup.py install for requests 18:09:56 INFO - Running setup.py install for docopt 18:09:56 INFO - Successfully installed blobuploader requests docopt 18:09:56 INFO - Cleaning up... 18:09:56 INFO - Return code: 0 18:09:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:09:56 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')]} 18:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:56 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')]} 18:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:56 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 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:09:56 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 18:09:56 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 18:09:56 INFO - Using env: {'DISPLAY': ':0', 18:09:56 INFO - 'HOME': '/home/cltbld', 18:09:56 INFO - 'LANG': 'en_US.UTF-8', 18:09:56 INFO - 'LANGUAGE': 'en_US:en', 18:09:56 INFO - 'LOGNAME': 'cltbld', 18:09:56 INFO - 'MAIL': '/var/mail/cltbld', 18:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:56 INFO - 'MOZ_NO_REMOTE': '1', 18:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:56 INFO - 'NO_EM_RESTART': '1', 18:09:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:09:56 INFO - 'PWD': '/builds/slave/test-pgo', 18:09:56 INFO - 'SHELL': '/bin/bash', 18:09:56 INFO - 'SHLVL': '1', 18:09:56 INFO - 'TERM': 'linux', 18:09:56 INFO - 'TMOUT': '86400', 18:09:56 INFO - 'USER': 'cltbld', 18:09:56 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:56 INFO - '_': '/tools/buildbot/bin/python'} 18:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:09:56 INFO - Running setup.py (path:/tmp/pip-T7nTDb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:09:56 INFO - Running setup.py (path:/tmp/pip-u2DDtN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:09:56 INFO - Running setup.py (path:/tmp/pip-IXK_jc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:09:56 INFO - Running setup.py (path:/tmp/pip-GJdZlq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:09:56 INFO - Running setup.py (path:/tmp/pip-1H9vIE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:09:56 INFO - Running setup.py (path:/tmp/pip-8y97pB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:09:56 INFO - Running setup.py (path:/tmp/pip-XRooU4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:09:56 INFO - Running setup.py (path:/tmp/pip-hNfxJR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:09:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:09:56 INFO - Running setup.py (path:/tmp/pip-9PzJTZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:09:57 INFO - Running setup.py (path:/tmp/pip-87GEOg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:09:57 INFO - Running setup.py (path:/tmp/pip-FW3kTs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:09:57 INFO - Running setup.py (path:/tmp/pip-m7CEPz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:09:57 INFO - Running setup.py (path:/tmp/pip-HkPPpw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:09:57 INFO - Running setup.py (path:/tmp/pip-ve2Wp0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:09:57 INFO - Running setup.py (path:/tmp/pip-jQNIzl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:09:57 INFO - Running setup.py (path:/tmp/pip-l1HHu3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:09:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:09:57 INFO - Running setup.py (path:/tmp/pip-Re_RJ4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:09:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:09:57 INFO - Running setup.py install for manifestparser 18:09:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:09:57 INFO - Running setup.py install for mozcrash 18:09:57 INFO - Running setup.py install for mozdebug 18:09:57 INFO - Running setup.py install for mozdevice 18:09:58 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Running setup.py install for mozfile 18:09:58 INFO - Running setup.py install for mozhttpd 18:09:58 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Running setup.py install for mozinfo 18:09:58 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Running setup.py install for mozInstall 18:09:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Running setup.py install for mozleak 18:09:58 INFO - Running setup.py install for mozlog 18:09:58 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Running setup.py install for moznetwork 18:09:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Running setup.py install for mozprocess 18:09:58 INFO - Running setup.py install for mozprofile 18:09:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:09:58 INFO - Running setup.py install for mozrunner 18:09:59 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:09:59 INFO - Running setup.py install for mozscreenshot 18:09:59 INFO - Running setup.py install for moztest 18:09:59 INFO - Running setup.py install for mozversion 18:09:59 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:09:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:09:59 INFO - Cleaning up... 18:09:59 INFO - Return code: 0 18:09:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:09:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:09:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:09:59 INFO - Using env: {'DISPLAY': ':0', 18:09:59 INFO - 'HOME': '/home/cltbld', 18:09:59 INFO - 'LANG': 'en_US.UTF-8', 18:09:59 INFO - 'LANGUAGE': 'en_US:en', 18:09:59 INFO - 'LOGNAME': 'cltbld', 18:09:59 INFO - 'MAIL': '/var/mail/cltbld', 18:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:59 INFO - 'MOZ_NO_REMOTE': '1', 18:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:59 INFO - 'NO_EM_RESTART': '1', 18:09:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:09:59 INFO - 'PWD': '/builds/slave/test-pgo', 18:09:59 INFO - 'SHELL': '/bin/bash', 18:09:59 INFO - 'SHLVL': '1', 18:09:59 INFO - 'TERM': 'linux', 18:09:59 INFO - 'TMOUT': '86400', 18:09:59 INFO - 'USER': 'cltbld', 18:09:59 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:59 INFO - '_': '/tools/buildbot/bin/python'} 18:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:09:59 INFO - Running setup.py (path:/tmp/pip-sY0ZTC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 18:09:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:09:59 INFO - Running setup.py (path:/tmp/pip-RLDYbQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:09:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:09:59 INFO - Running setup.py (path:/tmp/pip-WX8zzd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:09:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:09:59 INFO - Running setup.py (path:/tmp/pip-VJ9qsP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:09:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:09:59 INFO - Running setup.py (path:/tmp/pip-GtsDu2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 18:09:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:09:59 INFO - Running setup.py (path:/tmp/pip-0dc0Yi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:10:00 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)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:10:00 INFO - Running setup.py (path:/tmp/pip-WvUCMx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:10:00 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)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:10:00 INFO - Running setup.py (path:/tmp/pip-lKKdWN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:10:00 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)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:10:00 INFO - Running setup.py (path:/tmp/pip-adDQPI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:10:00 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)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:10:00 INFO - Running setup.py (path:/tmp/pip-VZCnop-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:10:00 INFO - Running setup.py (path:/tmp/pip-5RlP8U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:10:00 INFO - Running setup.py (path:/tmp/pip-xc8FRG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:10:00 INFO - Running setup.py (path:/tmp/pip-pqhAD4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:10:00 INFO - Running setup.py (path:/tmp/pip-2X7QgN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:10:00 INFO - Running setup.py (path:/tmp/pip-2blhkm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:10:00 INFO - Running setup.py (path:/tmp/pip-t6A9hV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 18:10:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:10:00 INFO - Running setup.py (path:/tmp/pip-bFtCL6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:10:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:02 INFO - Downloading blessings-1.5.1.tar.gz 18:10:02 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:10:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:10:02 INFO - Installing collected packages: blessings 18:10:02 INFO - Running setup.py install for blessings 18:10:02 INFO - Successfully installed blessings 18:10:02 INFO - Cleaning up... 18:10:02 INFO - Return code: 0 18:10:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:10:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:10:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:10:02 INFO - Reading from file tmpfile_stdout 18:10:02 INFO - Current package versions: 18:10:02 INFO - argparse == 1.2.1 18:10:02 INFO - blessings == 1.5.1 18:10:02 INFO - blobuploader == 1.2.4 18:10:02 INFO - docopt == 0.6.1 18:10:02 INFO - manifestparser == 1.1 18:10:02 INFO - mozInstall == 1.12 18:10:02 INFO - mozcrash == 0.16 18:10:02 INFO - mozdebug == 0.1 18:10:02 INFO - mozdevice == 0.46 18:10:02 INFO - mozfile == 1.2 18:10:02 INFO - mozhttpd == 0.7 18:10:02 INFO - mozinfo == 0.8 18:10:02 INFO - mozleak == 0.1 18:10:02 INFO - mozlog == 3.0 18:10:02 INFO - moznetwork == 0.27 18:10:02 INFO - mozprocess == 0.22 18:10:02 INFO - mozprofile == 0.27 18:10:02 INFO - mozrunner == 6.11 18:10:02 INFO - mozscreenshot == 0.1 18:10:02 INFO - mozsystemmonitor == 0.0 18:10:02 INFO - moztest == 0.7 18:10:02 INFO - mozversion == 1.4 18:10:02 INFO - psutil == 3.1.1 18:10:02 INFO - requests == 1.2.3 18:10:02 INFO - wsgiref == 0.1.2 18:10:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:10:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 18:10:03 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 18:10:03 INFO - Using env: {'DISPLAY': ':0', 18:10:03 INFO - 'HOME': '/home/cltbld', 18:10:03 INFO - 'LANG': 'en_US.UTF-8', 18:10:03 INFO - 'LANGUAGE': 'en_US:en', 18:10:03 INFO - 'LOGNAME': 'cltbld', 18:10:03 INFO - 'MAIL': '/var/mail/cltbld', 18:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:03 INFO - 'MOZ_NO_REMOTE': '1', 18:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:03 INFO - 'NO_EM_RESTART': '1', 18:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:10:03 INFO - 'PWD': '/builds/slave/test-pgo', 18:10:03 INFO - 'SHELL': '/bin/bash', 18:10:03 INFO - 'SHLVL': '1', 18:10:03 INFO - 'TERM': 'linux', 18:10:03 INFO - 'TMOUT': '86400', 18:10:03 INFO - 'USER': 'cltbld', 18:10:03 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:03 INFO - '_': '/tools/buildbot/bin/python'} 18:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:03 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)) 18:10:03 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)) 18:10:03 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)) 18:10:03 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)) 18:10:03 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)) 18:10:03 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)) 18:10:03 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)) 18:10:03 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)) 18:10: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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:10: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 mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:10:03 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)) 18:10:03 INFO - Cleaning up... 18:10:03 INFO - Return code: 0 18:10:03 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:10:03 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')]} 18:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:03 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')]} 18:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:03 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 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:10:03 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 18:10:03 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 18:10:03 INFO - Using env: {'DISPLAY': ':0', 18:10:03 INFO - 'HOME': '/home/cltbld', 18:10:03 INFO - 'LANG': 'en_US.UTF-8', 18:10:03 INFO - 'LANGUAGE': 'en_US:en', 18:10:03 INFO - 'LOGNAME': 'cltbld', 18:10:03 INFO - 'MAIL': '/var/mail/cltbld', 18:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:03 INFO - 'MOZ_NO_REMOTE': '1', 18:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:03 INFO - 'NO_EM_RESTART': '1', 18:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:10:03 INFO - 'PWD': '/builds/slave/test-pgo', 18:10:03 INFO - 'SHELL': '/bin/bash', 18:10:03 INFO - 'SHLVL': '1', 18:10:03 INFO - 'TERM': 'linux', 18:10:03 INFO - 'TMOUT': '86400', 18:10:03 INFO - 'USER': 'cltbld', 18:10:03 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:03 INFO - '_': '/tools/buildbot/bin/python'} 18:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:03 INFO - Downloading/unpacking jsonschema 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:10:05 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 18:10:05 INFO - Downloading/unpacking functools32 (from jsonschema) 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:05 INFO - Downloading functools32-3.2.3-2.tar.gz 18:10:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:10:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:10:05 INFO - warning: no files found matching '*.txt' 18:10:05 INFO - no previously-included directories found matching 'build' 18:10:05 INFO - no previously-included directories found matching 'dist' 18:10:05 INFO - no previously-included directories found matching '.git*' 18:10:05 INFO - Installing collected packages: jsonschema, functools32 18:10:05 INFO - Running setup.py install for functools32 18:10:05 INFO - warning: no files found matching '*.txt' 18:10:05 INFO - no previously-included directories found matching 'build' 18:10:05 INFO - no previously-included directories found matching 'dist' 18:10:05 INFO - no previously-included directories found matching '.git*' 18:10:05 INFO - Successfully installed jsonschema functools32 18:10:05 INFO - Cleaning up... 18:10:05 INFO - Return code: 0 18:10:05 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 18:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb45aff9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, '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': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', '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 18:10:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 18:10:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:10:05 INFO - Using env: {'DISPLAY': ':0', 18:10:05 INFO - 'HOME': '/home/cltbld', 18:10:05 INFO - 'LANG': 'en_US.UTF-8', 18:10:05 INFO - 'LANGUAGE': 'en_US:en', 18:10:05 INFO - 'LOGNAME': 'cltbld', 18:10:05 INFO - 'MAIL': '/var/mail/cltbld', 18:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:05 INFO - 'MOZ_NO_REMOTE': '1', 18:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:05 INFO - 'NO_EM_RESTART': '1', 18:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:10:05 INFO - 'PWD': '/builds/slave/test-pgo', 18:10:05 INFO - 'SHELL': '/bin/bash', 18:10:05 INFO - 'SHLVL': '1', 18:10:05 INFO - 'TERM': 'linux', 18:10:05 INFO - 'TMOUT': '86400', 18:10:05 INFO - 'USER': 'cltbld', 18:10:05 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:05 INFO - '_': '/tools/buildbot/bin/python'} 18:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:05 INFO - Downloading/unpacking flake8 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:10:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 18:10:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:10:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 18:10:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 18:10:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:10:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 18:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:10:08 INFO - no previously-included directories found matching 'docs/_build' 18:10:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:10:08 INFO - Running setup.py install for pep8 18:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:10:08 INFO - no previously-included directories found matching 'docs/_build' 18:10:08 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 18:10:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:10:08 INFO - Cleaning up... 18:10:08 INFO - Return code: 0 18:10:08 INFO - Running post-action listener: _resource_record_post_action 18:10:08 INFO - Running post-action listener: _start_resource_monitoring 18:10:08 INFO - Starting resource monitoring. 18:10:08 INFO - ##### 18:10:08 INFO - ##### Running install step. 18:10:08 INFO - ##### 18:10:08 INFO - Running pre-action listener: _resource_record_pre_action 18:10:08 INFO - Running main action method: install 18:10:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:10:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:10:08 INFO - Reading from file tmpfile_stdout 18:10:08 INFO - Detecting whether we're running mozinstall >=1.0... 18:10:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:10:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:10:08 INFO - Reading from file tmpfile_stdout 18:10:08 INFO - Output received: 18:10:08 INFO - Usage: mozinstall [options] installer 18:10:08 INFO - Options: 18:10:08 INFO - -h, --help show this help message and exit 18:10:08 INFO - -d DEST, --destination=DEST 18:10:08 INFO - Directory to install application into. [default: 18:10:08 INFO - "/builds/slave/test-pgo"] 18:10:08 INFO - --app=APP Application being installed. [default: firefox] 18:10:08 INFO - mkdir: /builds/slave/test-pgo/build/application 18:10:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:10:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:10:22 INFO - Reading from file tmpfile_stdout 18:10:22 INFO - Output received: 18:10:22 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:10:22 INFO - Running post-action listener: _resource_record_post_action 18:10:22 INFO - ##### 18:10:22 INFO - ##### Running run-tests step. 18:10:22 INFO - ##### 18:10:22 INFO - Running pre-action listener: _resource_record_pre_action 18:10:22 INFO - Running main action method: run_tests 18:10:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:10:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:10:22 INFO - Python 2.7.3 18:10:22 INFO - Return code: 0 18:10:22 INFO - grabbing minidump binary from tooltool 18:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:22 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25d9e30>, '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 0x25dad30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25db1c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:10:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 18:10:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:10:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:10:22 INFO - Return code: 0 18:10:22 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:10:22 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:10:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:10:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:10:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:10:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 18:10:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 18:10:23 INFO - Return code: 0 18:10:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:10:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --webServer localhost 18:10:23 INFO - Using env: {'DISPLAY': ':0', 18:10:23 INFO - 'HOME': '/home/cltbld', 18:10:23 INFO - 'LANG': 'en_US.UTF-8', 18:10:23 INFO - 'LANGUAGE': 'en_US:en', 18:10:23 INFO - 'LOGNAME': 'cltbld', 18:10:23 INFO - 'MAIL': '/var/mail/cltbld', 18:10:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:10:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:23 INFO - 'MOZ_NO_REMOTE': '1', 18:10:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:10:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:23 INFO - 'NO_EM_RESTART': '1', 18:10:23 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:10:23 INFO - 'PWD': '/builds/slave/test-pgo', 18:10:23 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:10:23 INFO - 'SHELL': '/bin/bash', 18:10:23 INFO - 'SHLVL': '1', 18:10:23 INFO - 'TERM': 'linux', 18:10:23 INFO - 'TMOUT': '86400', 18:10:23 INFO - 'USER': 'cltbld', 18:10:23 INFO - 'XDG_SESSION_COOKIE': 'd7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090', 18:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:23 INFO - '_': '/tools/buildbot/bin/python'} 18:10:23 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost'] with output_timeout 3600 18:10:24 INFO - mozversion INFO | application_buildid: 20151214205110 18:10:24 INFO - mozversion INFO | application_changeset: d1a4b331d80bc4daea15a7df4057205483b1f276 18:10:24 INFO - mozversion INFO | application_display_name: Nightly 18:10:24 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:10:24 INFO - mozversion INFO | application_name: Firefox 18:10:24 INFO - mozversion INFO | application_remotingname: firefox 18:10:24 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:10:24 INFO - mozversion INFO | application_vendor: Mozilla 18:10:24 INFO - mozversion INFO | application_version: 44.0 18:10:24 INFO - mozversion INFO | platform_buildid: 20151214205110 18:10:24 INFO - mozversion INFO | platform_changeset: d1a4b331d80bc4daea15a7df4057205483b1f276 18:10:24 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:10:24 INFO - mozversion INFO | platform_version: 44.0 18:10:24 INFO - 2015-12-15 18:10:24,105 DEBUG : using testdate: 1450231824 18:10:24 INFO - 2015-12-15 18:10:24,106 DEBUG : actual date: 1450231824 18:10:24 INFO - 2015-12-15 18:10:24,115 INFO : starting webserver on 'localhost:41349' 18:10:24 INFO - 2015-12-15 18:10:24,116 INFO : Starting test suite talos-linux64-ix-068 18:10:24 INFO - 2015-12-15 18:10:24,116 INFO : Starting test dromaeo_css 18:10:24 INFO - 2015-12-15 18:10:24,116 DEBUG : operating with platform_type : linux_ 18:10:24 INFO - 2015-12-15 18:10:24,117 INFO : Initialising browser for dromaeo_css test... 18:10:24 INFO - 2015-12-15 18:10:24,131 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBZu7nW/profile http://localhost:41349/getInfo.html 18:10:27 INFO - 2015-12-15 18:10:27,744 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:10:27 INFO - 2015-12-15 18:10:27,744 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:10:27 INFO - 2015-12-15 18:10:27,744 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:10:27 INFO - 2015-12-15 18:10:27,744 DEBUG : BROWSER_OUTPUT: __metrics 18:10:27 INFO - 2015-12-15 18:10:27,832 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:10:29 INFO - 2015-12-15 18:10:29,001 INFO : Browser initialized. 18:10:29 INFO - 2015-12-15 18:10:29,002 INFO : Running cycle 1/1 for dromaeo_css test... 18:10:29 INFO - 2015-12-15 18:10:29,002 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBZu7nW/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:10:40 INFO - 2015-12-15 18:10:40,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 131555328 18:10:40 INFO - 2015-12-15 18:10:40,683 DEBUG : BROWSER_OUTPUT: 18:11:31 INFO - 2015-12-15 18:11:31,489 DEBUG : BROWSER_OUTPUT: 1450231891488 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] 18:13:39 INFO - 2015-12-15 18:13:39,572 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41349/tests/dromaeo/cssquery-ext.html) 18:13:40 INFO - 2015-12-15 18:13:40,596 DEBUG : BROWSER_OUTPUT: RSS: Main: 234266624 18:13:40 INFO - 2015-12-15 18:13:40,597 DEBUG : BROWSER_OUTPUT: 18:16:42 INFO - 2015-12-15 18:16:42,521 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/dromaeo/cssquery-ext.html (next: http://localhost:41349/tests/dromaeo/cssquery-jquery.html) 18:16:43 INFO - 2015-12-15 18:16:43,083 DEBUG : BROWSER_OUTPUT: RSS: Main: 216768512 18:16:43 INFO - 2015-12-15 18:16:43,083 DEBUG : BROWSER_OUTPUT: 18:19:44 INFO - 2015-12-15 18:19:44,771 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41349/tests/dromaeo/cssquery-mootools.html) 18:19:45 INFO - 2015-12-15 18:19:45,334 DEBUG : BROWSER_OUTPUT: RSS: Main: 201707520 18:19:45 INFO - 2015-12-15 18:19:45,334 DEBUG : BROWSER_OUTPUT: 18:22:46 INFO - 2015-12-15 18:22:46,966 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41349/tests/dromaeo/cssquery-prototype.html) 18:22:47 INFO - 2015-12-15 18:22:47,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 193859584 18:22:47 INFO - 2015-12-15 18:22:47,532 DEBUG : BROWSER_OUTPUT: 18:25:49 INFO - 2015-12-15 18:25:49,861 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41349/tests/dromaeo/cssquery-yui.html) 18:25:50 INFO - 2015-12-15 18:25:50,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 180977664 18:25:50 INFO - 2015-12-15 18:25:50,435 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,470 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/dromaeo/cssquery-yui.html (next: http://localhost:41349/tests/dromaeo/cssquery-dojo.html) 18:28:52 INFO - 2015-12-15 18:28:52,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 238313472 18:28:52 INFO - 2015-12-15 18:28:52,721 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,722 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:28:52 INFO - 2015-12-15 18:28:52,722 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:28:52 INFO - 2015-12-15 18:28:52,723 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:28:52 INFO - 2015-12-15 18:28:52,723 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:28:52 INFO - 2015-12-15 18:28:52,724 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3258.5763850699414;3379.966200337996;3401.4387626041703;3402.7958322500635;3432.5537680101584;5544.223808666785;5617.185508101323;5632.464915832995;5637.154426835977;5650.463205995434;5654.264945557082;5677.716114194294;5679.148127780835;5696.971515142429;5698.971505142468;4938.38270216223;5108.2848401223855;5151.6908985461;5208.374995000586;5242.475752424765;5005.57452616528;5082.949170508285;5087.364079490064;5112.769925353368;5120.513551212647;5106.387233531963;5117.692938423688;5131.871703207413;5196.168613021917;5198.298229738974;5081.796728130888;5102.6173037022;5166;5170.991656626959;5184.844454666347;4860.198067318888;4878.170710979142;4959.2809042688605;5068.645194836363;5080.034793389244;5399.460053994609;5400.108982018001;5402.135658294654;5428.267183930158;5459.153831156177;6042.274927008743;6043.7280322385595;6044.123602077674;6051.152838602599;6052.9697351513405;5879.412058794129;5993.61041532299;5995.190649262381;6090.725917333731;6123.418275263843;5749.281339832479;5749.626274292157;5751.942480575224;5754.338251101135;5787.305523337184;5782.2194003808945;6234.470070044006;6240.594361366497;6242.500599951993;6252.218472690914;5888.116782482662;5888.9705551472825;6009.519238460913;6014.308354539196;6046.758129674852;6091.878162436727;6091.908621370683;6097.664628445478;6099.054646529795;6102.7558897644485;5747.39652336507;5817.650940943557;6058.454739073504;6064.393560643988;6069.514438844882;6027.78902738402;6130.892302157565;6131.468419844907;6138.754449822047;6139.6009259398;4670.719756814534;4672.369230153886;4673.065386922629;4681.344611754357;4697.271922851964;3999.7600143991453;4043.332850079704;4072.857449989236;4180.979095104505;4208.873733787991;5685.374608793029;5690.800821971294;5697.857553561194;5699.487046165865;5776.826695199151;5981.970090149522;5985.191999080155;5986.640801551921;5987.730552125165;6021.698915054318;5601.075822489325;5666.2350582670815;5733.88532229353;5766.94233057664;5836.88326233473;5253.159494480865;5283;5284.101702710548;5285.68285902847;5289.074411977888;6234.594751341239;6249.875002499924;6251.437370636573;6252.062190671436;6275.309715931244;5896.410358964155;5992.280926288873;6040;6110.633361998294;6122.938051683444;6333.65164915934;6335.334789847184;6338.176037115146;6353.618782873043;6356.523260755369;5680.914786278292;6038.5772995889865;6039.396060393926;6053.878922421615;6053.909191362045;5852.561057920588;5989.520838332835;6107.037429747615;6107.75279946992;6139.785107521216;5962.165296858357;5964.522838172849;5965.254343207099;5968.820935371946;5995.820125396245;5643.35101463337;5712.314522257189;5958.851028724316;5975.641461512323;5975.970120149545;5680.602357834912;5745.91381129275;5748.223989761496;5752.626079304831;5791.7683292667825;5933.199018132497;5937.376575459514;5945.613535120204;5998.430149135826;5999.100134979788;5564.137558678412;5647.915281270702;5684.175794509691;5816.854578635568;5837.766489340379 18:28:52 INFO - 2015-12-15 18:28:52,725 DEBUG : BROWSER_OUTPUT: |1;ext.html;607.7720854679495;644.1658052821598;649.9545031847773;655.839319366755;662.82435154684;35282.647173528254;42766.78616606916;43151.99999999996;43184.78407607965;43218.56781432185;47463.288050679264;48232;48232;48257.75871120648;48294.51705482944;8373.246407823295;8444.662213511452;9188.954055229715;9351.812963740755;9358.157765801077;11087.168462365315;11260.380679062648;11260.54957801687;11317.547298108066;11330.263532870336;11095.944520277388;11219.214654974156;11224.214304998653;11230.943845280763;11235.606753763619;11174.553017879274;11248;11270.042046426064;11274.774504509945;11275.492602832892;11037.227394082418;11171.106311495061;11186.384748838811;11191.776164476665;11198.216124871262;43905.34141987873;44056.339154912705;44094.33858492125;44111.132776369435;44135.55864441347;3338.21551935295;3415.043787739412;3450.4996775467416;3498.8250587470525;3511.613722490524;30169;30387.544186837215;30509;30511.69488305133;30637.540436893465;3891.9221615567812;3921.1961547882615;3999.1801680655394;4049.696272779524;4074.5314288856866;303686;304778;304823;305168.47415763;305451;61135.388646112966;62129;68049.3195068043;75918;76347;70226.64886675533;70340.2965970344;70386.296137039;70509;70574.64712676506;17233.827661723473;17352.47942561725;17374;17469.65060698779;17475.126243687766;13040.739185216242;13058.934705326412;13206.801897971538;13224.537141200011;13226.735465290833;22418.55162896733;22532;22702;22724.09103635869;22806.885965570065;7855.607219638995;8138.674453021813;8153.5923203839575;8182.631781569844;8290;954.7326748510428;1001.62439085343;1051.3113910388709;1124.7413094988199;1125.3810404277779;30733.38533229323;31240;31313.06060818179;31912.521312180335;31982.520262196085;1080.1358912869673;1091.8908109189176;1123.971900702489;1165.3823473559028;1180.7579446213506;14250.429982800779;14827.258637068104;14911.627209319637;15165.772513412308;16584.336626535045;286877;289116;289691;290810;291079;54510;54650.453495464535;54832.72583637056;54994.72502637461;55083;1430.8283006039135;1432.1550285331705;1451.078565111141;1453.9563813085624;1458.8103546538882;30463.238419039702;30483.2379190522;30898.53652195174;31271.84364078165;31709.5243571342;49731.75134124306;51005.234921475465;52520;52936.20595690991;56820;256252;261817;263266;263996;264107;37414.62585374111;37889.62110378861;38069.4289585662;38087.61912380951;38107.23785524329;49324.75337623289;50048.24927626162;50635.74682126566;50888.49111508838;51958.220626691385;41811.581884181986;42071.7896410516;42609.36085958651;43115.56884431116;43845;42758.358624620036;42910;43878.561214387446;47445.762771185924;48087.27869081897;43224.56775432205;44263.336049958634;44277.11445771002;44366.556334436245;44560.77719611381;37246;38264.80867595644;38783.612163878;38962.220755585295;39257.21485570216;28854.711452886044;29210;29258.7074129256;29282.56076158902;29565.85217073901 18:28:52 INFO - 2015-12-15 18:28:52,727 DEBUG : BROWSER_OUTPUT: |2;jquery.html;389.2545774841181;390.6757391365167;395.7486995757693;396.3499860229224;397.98607048753297;14063;18091.27634894604;19994.900025499872;20017.59964800705;20986.160553577876;17097.40159094432;17134.31462741492;19406;19435.902820485913;19462.221511139574;5764.769409223626;5849.736761845706;5879.294484661847;5888.175655408247;5898.616589921663;5778.364379918226;5858.062709966406;5923.792667256647;5926.199963004982;5943.554233432488;4424.690271680984;4544.8863778405475;5998.4001599839885;6005.369436209201;6010.549208809335;6348.460380867632;6462.709178086998;6512.251091124534;6512.348765123474;6514.153160089182;5703.62926409782;5816.447437493432;5819.185314056035;5819.970900145515;5821.417858214187;23260;23651.29046128602;24097.277081687578;24107.39731506709;24421.633675494882;737.9963100184518;743.7657138001513;743.773149189497;2188.8248940084914;2288.1994144842743;14383.993120481571;14749.926250368679;14769.113853168845;15081.698366032728;15144.242787860563;1924.5862139639905;1936.912838922252;1939;1983.4545499987476;2030.6547886859269;68827;69098.65450672715;70242;70872.29127708709;71088;11709.297442153498;12118.697032574257;12222;12229.877701223051;12247.938760306142;16432.507024789276;16434.342626295052;16441.506754797374;16453;16508.42220522276;8799.428037177562;8859.512726800089;8860.512671802984;8874.112588741204;8901.73294801157;8316.459430137022;8327.458715183615;8356.28971537414;8407.41148119638;8441.95779021101;6975.128108986377;6988.266232045562;7058.188308344504;7071.823204419828;7083.150021997393;1268.8604253532103;1311.6327428320085;1788.9313514589555;2053.9897300513403;2112.5246819465497;498.27252211770497;519.4727351737989;541.6858222231097;564.6470955652717;580.7851095094758;12856.100072994988;13037.804432933513;13525.729485410237;13649.385777640031;13689.863101369057;567.1634339349426;570.6490508337342;579.1313030454319;585.1427658480346;597.4264705882315;6741.696623651947;7061.470389720792;7069.646517674096;7117.6085315308155;7336.082989626297;391565;408157;519210;522251;561911;85237;88445;89095.55452222827;90406;90641;536.7450461031057;548;549.8872731090117;550.7026205848797;550.713628912971;11354.489047992694;11527.596534121265;11685;11919.582814601446;11982.70043248891;39259;39448.802755986035;39588.80205599069;40039.59960400359;40224.597754022085;51988.220176696625;52069;54469.72765136149;54678.453215467336;54803;9393.201577865959;9553;9635.903640963501;9662;9664.661736839176;13372;13876.930615347263;13886.305684715926;14080.436782528584;14099.859001410265;11865.466054027416;12076.75846483083;12342;12370.567030153901;12381.380930953597;11886.227395219628;11891.524339026342;12032.819507707567;12045.51817927273;12131.878681213075;12121.575744848888;12436.129470936947;12494.875051249372;12919.935400323315;13107.213567186;9106;9609.855852162083;9893.50532473359;9917.256205784452;9926.503674816375;9553.952230239081;11906.464209110438;12046.939765301116;12201.633950981484;12290.20113692607 18:28:52 INFO - 2015-12-15 18:28:52,728 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1571.8663913567345;1610.2190437637737;1650.2656317938515;1831.8809277396967;1913.4546654203555;7907.4069444791585;8284.378671599632;8285.875711864326;8289.295409890166;8294.75115746527;7971.083325417579;7996.080450748165;7997.960010199956;8013.398995075364;8019.759407217778;2208.1277895231456;2226.3432287475193;2238.697775800262;2241.540484200743;2248.3592176229777;2417.7340492545804;2427.5751743444825;2427.78149966503;2428.659987601737;2428.7206971198275;1995.2118913029344;2038.0523056778595;2211.1266049910278;2332.1720789119872;2375.10933399975;2223.83321250906;2243.1924507177405;2245.3376254005107;2257.1874125314876;2258.096761295487;2373.703287089114;2426.3812727754485;2427.0898413095088;2428.7206971198184;2429.2226487524;7003.719851205997;7238.601876896769;7646.808829779245;7879;7888.802779930547;5009.5491405773655;5085.74571271435;5093.592512599021;5094.057599344133;5095.974520127413;3336.59960804705;3348.882789102394;3352.8994130176134;3395.2700169463683;3445;1075.3118004477142;1087.6519513755602;1138.4876805437586;1141.5148561861258;1149.919505634606;11221.04621107207;11245.493952772145;11251.381174035425;11257;11258.887411125866;4651.37206477128;4652.232381657056;4682.1338052460405;4691.9530804692195;4716;9519.524023798782;9519.571619277149;9526.476043817518;9528.475933823567;9538.666146684833;5183.844484665391;5205.895882082337;5220.504052115043;5233.450487698737;5233.555147812479;4698.718076915396;4705.882352941204;4721.244600863845;4723.7874295656775;4724.00795832879;3839.3665045267776;3874.1864208516527;3881.184951160288;3899.2981263372867;3904.1996390739832;1262.2868079535033;1266.2402558464848;1270.0538099116218;1270.3203785974458;1283.9871601284055;1086.3481910853425;1100.862392200975;1105.1214284643727;1344.9865501344862;1467.1783801071433;7747.418943579141;7814.960925195338;7848.293653571206;7857.096433910059;7857.842843143105;551.2695678226286;554.1078863030517;560.7672815781486;561.6209058885236;563.5294529068274;5867.618604790675;5889.293284805851;6253;6307.779227727099;6319.494440444753;9335.393199442151;9470.100340467645;9557.90442095584;9558.378705384128;9560.043995600385;9325.440473571607;9472.621095156255;9485.288603354777;9535.046495350409;9589.184919281939;7517.436192285601;7621.542707437571;7640.197779233212;7644.847103057908;7646.885296720554;7583.203763604963;7590.127135379502;7760.84478310442;7767.92232077672;7774;8735.126487351468;8833.734987950373;8903.19871211594;8906.955465222632;8938.10618938101;8863.778405539913;9050.09499050116;9057.864132037894;9079.364444488823;9087.36388452802;7768.262015108556;7802.180771019188;7906;7921.207879212033;7925.960370198112;7815.570143642154;7867.488613240121;7870.685172593032;7877.566733829694;7894.407919406182;7321.78034658961;7440.813979650552;7490.101187857492;7608.201138880338;7675.769726908202;7239.131304243525;7437.256274372519;7476.514026588255;7484.438667100098;7622.58075805836;7297.306755858185;7336.449766267658;7346.5592064476305;7351.595662238628;7357.926420735724;6961.582305061713;6968.059311992816;6970.372666460043;6988.231294557696;6998;6822.931770682229;6970.23327433992;6970.895436568354;7029.5430796998035;7076.610786406591 18:28:52 INFO - 2015-12-15 18:28:52,728 DEBUG : BROWSER_OUTPUT: |4;prototype.html;362.12727327141596;363.3151509404771;368.9981550092249;369.3812863453716;370.04712864374227;5911.88176236475;5948.553858460622;5958.642481451105;5969.791057312995;5976.432238937299;5710.400407957157;5714.485696287334;5820.039693450575;5837.445442682938;5855.590108692393;4612.1236964976715;4614.584687378136;4620.214563524192;4629.953700462986;4636.211843986522;4620.8151673933;4621.2837010263465;4632.119897219534;4636.420447444069;4640.025594625133;4590.449146102473;4591.311303304515;4592.334111553822;4600.5629465200755;4605.102005109005;4585.2663573828195;4658.045100754337;4674.392328997243;4675.602573781232;4689.7655117244;4175.958240417587;4176.436181115541;4184.476940382452;4185.22573323936;4186.037211441355;5674.659520428746;5683;5690.687012214326;5696.401877802854;5702.0021496238;5285.976073062108;5408.945910540883;5412.810551630674;5415.296011518517;5417.404085550586;6146.231721034871;6201.844953876144;6208.41020103094;6225.377462253784;6231.937680623181;4540.114677637862;4540.18276710192;4545.11370282795;4546.47715512717;4546.568076032771;6416.00551914454;6417.871642567169;6425.357464253584;6425.389587989133;6430.7106180222;4615.076984603093;4667.206574882278;4670;4738.597219236334;4744;4725.740084295328;4750.192467280571;4796.136695394863;4797.712137271775;4798.904021919542;5335.039692855324;5335.359756829128;5348.786048558092;5349.1976203569775;5353.9732301338245;6263.6241825490615;6299.307076221612;6308.873822523524;6309.589876658094;6311.368863113652;1653.3221379234697;1689.2145152504145;1691.6109294862251;1697.5331783759318;1708.086173896959;1075.8117329467311;1116.7487315354142;1137.3289759042204;1197.3414621957888;1202.1584890576632;5879.294484661782;5895.322037965603;5908.291005079418;5912.793052243151;5923.318818335946;6277.843053923597;6334.714937827809;6344.460720838781;6355.332690067568;6361.141245931833;5862.14998825168;5976.073708575178;5978.163057172;5983.491403230687;5987.042073268256;3528.558930133733;3568.518250036212;3568.553930758655;3572.982135089308;3574;6583.7695680651905;6603.537752357385;6613.966930165415;6619.238787539494;6695.933040669531;6586.440152586988;6594.934050659528;6598.703058362289;6600.966995165089;6605.273419923805;6544.345565443417;6642.535022548472;6642.767503137367;6643.269240383554;6647.268800431949;6394.648294343856;6416.903746443901;6420.678966051585;6425.036244563353;6425.710843011982;6323.272823625342;6365.586236894587;6374.776882809079;6375;6378.936210637834;6603.834904127435;6682.665866706548;6714.127163468718;6721.731130754715;6728.495362847708;4543.931841022321;4580.152671755736;4582.358469814162;4583.083383323282;4585.839495617637;6369.01280301554;6390.4248617624635;6413.262474815456;6415.326390729093;6434.903476447764;6210.472109870711;6221.96889015552;6272.247330320391;6281.309056003927;6282.905756413566;6256.937430625636;6261.686915654108;6262.937370626418;6263.655498947602;6268.561200716089;6038.094285857156;6110.7555697771595;6113.113598528277;6121.3572574879;6129.325774164749;5987.820365389045;6038.486728628116;6180.598261113013;6276.121343012073;6278.277998030102;6059.575829691879;6081.483073938764;6090.1169330445955;6094.451499365078;6101.267847858286 18:28:52 INFO - 2015-12-15 18:28:52,729 DEBUG : BROWSER_OUTPUT: |5;yui.html;919.1643778941963;958.6117622362946;959.1607343574373;962.93259471837;981.9803603927917;13937.651558711024;14241.71516569668;17210.91394543027;17307.22117504712;17355.132243387845;14817;14885;14893;14909.478168264113;14910.10539367636;744.5867543513348;765.6439755513685;896.9461832290051;907.2197909797585;921.4010892919622;906.8866391701038;907.2016625369698;911.6900253913668;913.2785099771173;913.5386629751966;942.9198518125934;948.4119845695644;948.5684013773716;954.1555723184962;954.6610953111649;771.5949126708467;798.6925033861947;928.058021108572;961.0149596663476;963.2390411574918;702.6170736948325;704.4118161335291;705.2806137739477;705.4744215559441;706.0820932787356;14321.856781432156;14322.498712545012;14366.928165359212;14440;14452.349644266033;10402.479876006246;10940.288881222696;11011.61459348919;11066.114710823114;11127.443627818657;9889.357191782512;10552.841707374395;10601.416922069284;10737.731556711067;10907.672769816918;3345.6988871001727;3382.6279109297957;3400.472926696366;3403.829808509565;3404.676555727202;49474.25788613174;49793.751031244974;49817;50305;51511.742441287926;7704.537727736354;7815.4529182957785;7820.7262745804765;7830.177831327743;7939.0870049945;12639.304838233986;12652.557160499338;12664.303463309423;12707.237565746085;12735.745285094246;6961.860762784716;7078.858422831515;7119.537230080028;7147.749828755968;7244.130704315516;4517.41273634432;4584.9541504585195;4723.834665786681;4815.542523460265;4833.73414462208;2951.409718056397;2975.8363290019256;2982.776291778125;2983.8508074596616;2985.11938978002;448.1328629102697;448.6119506626744;462.9213033784265;470.63290633306076;471.4389876047561;599.8500374906273;608.4888693497484;654.201873714068;699.9720011199597;798.9800254993555;9195.632174712937;9687.17658998979;9762.121409073217;9787.90212097884;9832.065953734384;597.3996133885504;745.6334649059501;758.9013428254402;764.4495962906698;773.6170595555236;4226.682998775104;4535.916382405304;4604.608608268268;4695.2252878275385;4808.831690890802;60236.096458553155;60642;65420.67289663521;65463.34536654668;65648.6717566409;54272.45727542674;55974;56260.43739562552;59217;59532.404675953556;52111.478885210665;52638;52916.470835291926;53045.469545304055;53424;9520.285978551497;9559.044095590385;9834.508274586386;9879.308448408543;9883.456409897524;38508;39413;39542.802285989535;40436.59563404328;42748.78625606852;50724.74637626788;51331.48668513267;51829.481705182465;52112;52115.73942130265;8673.913260867563;8751.299896008431;8787.824243514966;8939.106089391269;9143.542822858963;13144.60566183016;13241.933790330986;13505.12216705911;13523.661908452368;14054.718905622034;12294.938525307316;12331.815022774845;12427.93786031064;12429.937850310691;12451.75096498083;12818.807717884425;13097.541586044443;13139.211647301192;13191.67020824487;13326.866731332562;12900.225986440844;12925.806112908502;12949.482020719443;12984.805227921777;13003.08978371505;10898.891011089787;11264.549418023187;11384.316940983568;11439.027682647069;11451.198416111125;10862;10944.452777361259;10946.945265273622;11063.83404248953;11131.332120072822 18:28:52 INFO - 2015-12-15 18:28:52,729 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:28:52 INFO - 2015-12-15 18:28:52,729 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:28:52 INFO - 2015-12-15 18:28:52,729 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,776 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: __startTimestamp1450232932721__endTimestamp 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5607.03 Median:5766.94 stddev:634.16 (11.0%) stddev-sans-first:608.76 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: Values: 3258.6 3380.0 3401.4 3402.8 3432.6 5544.2 5617.2 5632.5 5637.2 5650.5 5654.3 5677.7 5679.1 5697.0 5699.0 4938.4 5108.3 5151.7 5208.4 5242.5 5005.6 5082.9 5087.4 5112.8 5120.5 5106.4 5117.7 5131.9 5196.2 5198.3 5081.8 5102.6 5166.0 5171.0 5184.8 4860.2 4878.2 4959.3 5068.6 5080.0 5399.5 5400.1 5402.1 5428.3 5459.2 6042.3 6043.7 6044.1 6051.2 6053.0 5879.4 5993.6 5995.2 6090.7 6123.4 5749.3 5749.6 5751.9 5754.3 5787.3 5782.2 6234.5 6240.6 6242.5 6252.2 5888.1 5889.0 6009.5 6014.3 6046.8 6091.9 6091.9 6097.7 6099.1 6102.8 5747.4 5817.7 6058.5 6064.4 6069.5 6027.8 6130.9 6131.5 6138.8 6139.6 4670.7 4672.4 4673.1 4681.3 4697.3 3999.8 4043.3 4072.9 4181.0 4208.9 5685.4 5690.8 5697.9 5699.5 5776.8 5982.0 5985.2 5986.6 5987.7 6021.7 5601.1 5666.2 5733.9 5766.9 5836.9 5253.2 5283.0 5284.1 5285.7 5289.1 6234.6 6249.9 6251.4 6252.1 6275.3 5896.4 5992.3 6040.0 6110.6 6122.9 6333.7 6335.3 6338.2 6353.6 6356.5 5680.9 6038.6 6039.4 6053.9 6053.9 5852.6 5989.5 6107.0 6107.8 6139.8 5962.2 5964.5 5965.3 5968.8 5995.8 5643.4 5712.3 5958.9 5975.6 5976.0 5680.6 5745.9 5748.2 5752.6 5791.8 5933.2 5937.4 5945.6 5998.4 5999.1 5564.1 5647.9 5684.2 5816.9 5837.8 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,730 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48868.18 Median:3432.77 stddev:74318.62 (2165.0%) stddev-sans-first:74438.76 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: Values: 607.8 644.2 650.0 655.8 662.8 35282.6 42766.8 43152.0 43184.8 43218.6 47463.3 48232.0 48232.0 48257.8 48294.5 8373.2 8444.7 9189.0 9351.8 9358.2 11087.2 11260.4 11260.5 11317.5 11330.3 11095.9 11219.2 11224.2 11230.9 11235.6 11174.6 11248.0 11270.0 11274.8 11275.5 11037.2 11171.1 11186.4 11191.8 11198.2 43905.3 44056.3 44094.3 44111.1 44135.6 3338.2 3415.0 3450.5 3498.8 3511.6 30169.0 30387.5 30509.0 30511.7 30637.5 3891.9 3921.2 3999.2 4049.7 4074.5 303686.0 304778.0 304823.0 305168.5 305451.0 61135.4 62129.0 68049.3 75918.0 76347.0 70226.6 70340.3 70386.3 70509.0 70574.6 17233.8 17352.5 17374.0 17469.7 17475.1 13040.7 13058.9 13206.8 13224.5 13226.7 22418.6 22532.0 22702.0 22724.1 22806.9 7855.6 8138.7 8153.6 8182.6 8290.0 954.7 1001.6 1051.3 1124.7 1125.4 30733.4 31240.0 31313.1 31912.5 31982.5 1080.1 1091.9 1124.0 1165.4 1180.8 14250.4 14827.3 14911.6 15165.8 16584.3 286877.0 289116.0 289691.0 290810.0 291079.0 54510.0 54650.5 54832.7 54994.7 55083.0 1430.8 1432.2 1451.1 1454.0 1458.8 30463.2 30483.2 30898.5 31271.8 31709.5 49731.8 51005.2 52520.0 52936.2 56820.0 256252.0 261817.0 263266.0 263996.0 264107.0 37414.6 37889.6 38069.4 38087.6 38107.2 49324.8 50048.2 50635.7 50888.5 51958.2 41811.6 42071.8 42609.4 43115.6 43845.0 42758.4 42910.0 43878.6 47445.8 48087.3 43224.6 44263.3 44277.1 44366.6 44560.8 37246.0 38264.8 38783.6 38962.2 39257.2 28854.7 29210.0 29258.7 29282.6 29565.9 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28098.97 Median:508.87 stddev:79809.82 (15683.7%) stddev-sans-first:80006.58 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: Values: 389.3 390.7 395.7 396.3 398.0 14063.0 18091.3 19994.9 20017.6 20986.2 17097.4 17134.3 19406.0 19435.9 19462.2 5764.8 5849.7 5879.3 5888.2 5898.6 5778.4 5858.1 5923.8 5926.2 5943.6 4424.7 4544.9 5998.4 6005.4 6010.5 6348.5 6462.7 6512.3 6512.3 6514.2 5703.6 5816.4 5819.2 5820.0 5821.4 23260.0 23651.3 24097.3 24107.4 24421.6 738.0 743.8 743.8 2188.8 2288.2 14384.0 14749.9 14769.1 15081.7 15144.2 1924.6 1936.9 1939.0 1983.5 2030.7 68827.0 69098.7 70242.0 70872.3 71088.0 11709.3 12118.7 12222.0 12229.9 12247.9 16432.5 16434.3 16441.5 16453.0 16508.4 8799.4 8859.5 8860.5 8874.1 8901.7 8316.5 8327.5 8356.3 8407.4 8442.0 6975.1 6988.3 7058.2 7071.8 7083.2 1268.9 1311.6 1788.9 2054.0 2112.5 498.3 519.5 541.7 564.6 580.8 12856.1 13037.8 13525.7 13649.4 13689.9 567.2 570.6 579.1 585.1 597.4 6741.7 7061.5 7069.6 7117.6 7336.1 391565.0 408157.0 519210.0 522251.0 561911.0 85237.0 88445.0 89095.6 90406.0 90641.0 536.7 548.0 549.9 550.7 550.7 11354.5 11527.6 11685.0 11919.6 11982.7 39259.0 39448.8 39588.8 40039.6 40224.6 51988.2 52069.0 54469.7 54678.5 54803.0 9393.2 9553.0 9635.9 9662.0 9664.7 13372.0 13876.9 13886.3 14080.4 14099.9 11865.5 12076.8 12342.0 12370.6 12381.4 11886.2 11891.5 12032.8 12045.5 12131.9 12121.6 12436.1 12494.9 12919.9 13107.2 9106.0 9609.9 9893.5 9917.3 9926.5 9554.0 11906.5 12046.9 12201.6 12290.2 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5748.93 Median:6892.26 stddev:2973.42 (43.1%) stddev-sans-first:2965.19 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: Values: 1571.9 1610.2 1650.3 1831.9 1913.5 7907.4 8284.4 8285.9 8289.3 8294.8 7971.1 7996.1 7998.0 8013.4 8019.8 2208.1 2226.3 2238.7 2241.5 2248.4 2417.7 2427.6 2427.8 2428.7 2428.7 1995.2 2038.1 2211.1 2332.2 2375.1 2223.8 2243.2 2245.3 2257.2 2258.1 2373.7 2426.4 2427.1 2428.7 2429.2 7003.7 7238.6 7646.8 7879.0 7888.8 5009.5 5085.7 5093.6 5094.1 5096.0 3336.6 3348.9 3352.9 3395.3 3445.0 1075.3 1087.7 1138.5 1141.5 1149.9 11221.0 11245.5 11251.4 11257.0 11258.9 4651.4 4652.2 4682.1 4692.0 4716.0 9519.5 9519.6 9526.5 9528.5 9538.7 5183.8 5205.9 5220.5 5233.5 5233.6 4698.7 4705.9 4721.2 4723.8 4724.0 3839.4 3874.2 3881.2 3899.3 3904.2 1262.3 1266.2 1270.1 1270.3 1284.0 1086.3 1100.9 1105.1 1345.0 1467.2 7747.4 7815.0 7848.3 7857.1 7857.8 551.3 554.1 560.8 561.6 563.5 5867.6 5889.3 6253.0 6307.8 6319.5 9335.4 9470.1 9557.9 9558.4 9560.0 9325.4 9472.6 9485.3 9535.0 9589.2 7517.4 7621.5 7640.2 7644.8 7646.9 7583.2 7590.1 7760.8 7767.9 7774.0 8735.1 8833.7 8903.2 8907.0 8938.1 8863.8 9050.1 9057.9 9079.4 9087.4 7768.3 7802.2 7906.0 7921.2 7926.0 7815.6 7867.5 7870.7 7877.6 7894.4 7321.8 7440.8 7490.1 7608.2 7675.8 7239.1 7437.3 7476.5 7484.4 7622.6 7297.3 7336.4 7346.6 7351.6 7357.9 6961.6 6968.1 6970.4 6988.2 6998.0 6822.9 6970.2 6970.9 7029.5 7076.6 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,732 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5274.68 Median:5918.06 stddev:1520.85 (25.7%) stddev-sans-first:1479.74 18:28:52 INFO - 2015-12-15 18:28:52,734 DEBUG : BROWSER_OUTPUT: Values: 362.1 363.3 369.0 369.4 370.0 5911.9 5948.6 5958.6 5969.8 5976.4 5710.4 5714.5 5820.0 5837.4 5855.6 4612.1 4614.6 4620.2 4630.0 4636.2 4620.8 4621.3 4632.1 4636.4 4640.0 4590.4 4591.3 4592.3 4600.6 4605.1 4585.3 4658.0 4674.4 4675.6 4689.8 4176.0 4176.4 4184.5 4185.2 4186.0 5674.7 5683.0 5690.7 5696.4 5702.0 5286.0 5408.9 5412.8 5415.3 5417.4 6146.2 6201.8 6208.4 6225.4 6231.9 4540.1 4540.2 4545.1 4546.5 4546.6 6416.0 6417.9 6425.4 6425.4 6430.7 4615.1 4667.2 4670.0 4738.6 4744.0 4725.7 4750.2 4796.1 4797.7 4798.9 5335.0 5335.4 5348.8 5349.2 5354.0 6263.6 6299.3 6308.9 6309.6 6311.4 1653.3 1689.2 1691.6 1697.5 1708.1 1075.8 1116.7 1137.3 1197.3 1202.2 5879.3 5895.3 5908.3 5912.8 5923.3 6277.8 6334.7 6344.5 6355.3 6361.1 5862.1 5976.1 5978.2 5983.5 5987.0 3528.6 3568.5 3568.6 3573.0 3574.0 6583.8 6603.5 6614.0 6619.2 6695.9 6586.4 6594.9 6598.7 6601.0 6605.3 6544.3 6642.5 6642.8 6643.3 6647.3 6394.6 6416.9 6420.7 6425.0 6425.7 6323.3 6365.6 6374.8 6375.0 6378.9 6603.8 6682.7 6714.1 6721.7 6728.5 4543.9 4580.2 4582.4 4583.1 4585.8 6369.0 6390.4 6413.3 6415.3 6434.9 6210.5 6222.0 6272.2 6281.3 6282.9 6256.9 6261.7 6262.9 6263.7 6268.6 6038.1 6110.8 6113.1 6121.4 6129.3 5987.8 6038.5 6180.6 6276.1 6278.3 6059.6 6081.5 6090.1 6094.5 6101.3 18:28:52 INFO - 2015-12-15 18:28:52,734 DEBUG : BROWSER_OUTPUT: 18:28:52 INFO - 2015-12-15 18:28:52,734 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14856.86 Median:49805.38 stddev:17833.37 (35.8%) stddev-sans-first:17852.68 18:28:52 INFO - 2015-12-15 18:28:52,734 DEBUG : BROWSER_OUTPUT: Values: 919.2 958.6 959.2 962.9 982.0 13937.7 14241.7 17210.9 17307.2 17355.1 14817.0 14885.0 14893.0 14909.5 14910.1 744.6 765.6 896.9 907.2 921.4 906.9 907.2 911.7 913.3 913.5 942.9 948.4 948.6 954.2 954.7 771.6 798.7 928.1 961.0 963.2 702.6 704.4 705.3 705.5 706.1 14321.9 14322.5 14366.9 14440.0 14452.3 10402.5 10940.3 11011.6 11066.1 11127.4 9889.4 10552.8 10601.4 10737.7 10907.7 3345.7 3382.6 3400.5 3403.8 3404.7 49474.3 49793.8 49817.0 50305.0 51511.7 7704.5 7815.5 7820.7 7830.2 7939.1 12639.3 12652.6 12664.3 12707.2 12735.7 6961.9 7078.9 7119.5 7147.7 7244.1 4517.4 4585.0 4723.8 4815.5 4833.7 2951.4 2975.8 2982.8 2983.9 2985.1 448.1 448.6 462.9 470.6 471.4 599.9 608.5 654.2 700.0 799.0 9195.6 9687.2 9762.1 9787.9 9832.1 597.4 745.6 758.9 764.4 773.6 4226.7 4535.9 4604.6 4695.2 4808.8 60236.1 60642.0 65420.7 65463.3 65648.7 54272.5 55974.0 56260.4 59217.0 59532.4 52111.5 52638.0 52916.5 53045.5 53424.0 9520.3 9559.0 9834.5 9879.3 9883.5 38508.0 39413.0 39542.8 40436.6 42748.8 50724.7 51331.5 51829.5 52112.0 52115.7 8673.9 8751.3 8787.8 8939.1 9143.5 13144.6 13241.9 13505.1 13523.7 14054.7 12294.9 12331.8 12427.9 12429.9 12451.8 12818.8 13097.5 13139.2 13191.7 13326.9 12900.2 12925.8 12949.5 12984.8 13003.1 10898.9 11264.5 11384.3 11439.0 11451.2 10862.0 10944.5 10946.9 11063.8 11131.3 18:28:52 INFO - 2015-12-15 18:28:52,734 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:28:52 INFO - 2015-12-15 18:28:52,734 DEBUG : BROWSER_OUTPUT: 18:28:53 INFO - 2015-12-15 18:28:53,309 INFO : Browser exited with error code: 0 18:28:53 INFO - 2015-12-15 18:28:53,330 INFO : Completed test dromaeo_css (00:18:29) 18:28:53 INFO - 2015-12-15 18:28:53,330 INFO : Starting test kraken 18:28:53 INFO - 2015-12-15 18:28:53,330 DEBUG : operating with platform_type : linux_ 18:28:53 INFO - 2015-12-15 18:28:53,330 INFO : Initialising browser for kraken test... 18:28:53 INFO - 2015-12-15 18:28:53,336 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnY2uRc/profile http://localhost:41349/getInfo.html 18:28:56 INFO - 2015-12-15 18:28:56,941 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:28:56 INFO - 2015-12-15 18:28:56,941 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:28:56 INFO - 2015-12-15 18:28:56,941 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:28:56 INFO - 2015-12-15 18:28:56,941 DEBUG : BROWSER_OUTPUT: __metrics 18:28:57 INFO - 2015-12-15 18:28:57,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] 18:28:58 INFO - 2015-12-15 18:28:58,056 INFO : Browser initialized. 18:28:58 INFO - 2015-12-15 18:28:58,057 INFO : Running cycle 1/1 for kraken test... 18:28:58 INFO - 2015-12-15 18:28:58,057 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnY2uRc/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:29:09 INFO - 2015-12-15 18:29:09,692 DEBUG : BROWSER_OUTPUT: RSS: Main: 131284992 18:29:09 INFO - 2015-12-15 18:29:09,693 DEBUG : BROWSER_OUTPUT: 18:29:12 INFO - 2015-12-15 18:29:12,136 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41349/tests/kraken/driver.html?testName=audio-beat-detection) 18:29:12 INFO - 2015-12-15 18:29:12,702 DEBUG : BROWSER_OUTPUT: RSS: Main: 163315712 18:29:12 INFO - 2015-12-15 18:29:12,702 DEBUG : BROWSER_OUTPUT: 18:29:15 INFO - 2015-12-15 18:29:15,239 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41349/tests/kraken/driver.html?testName=audio-dft) 18:29:15 INFO - 2015-12-15 18:29:15,800 DEBUG : BROWSER_OUTPUT: RSS: Main: 160063488 18:29:15 INFO - 2015-12-15 18:29:15,801 DEBUG : BROWSER_OUTPUT: 18:29:18 INFO - 2015-12-15 18:29:18,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41349/tests/kraken/driver.html?testName=audio-fft) 18:29:19 INFO - 2015-12-15 18:29:19,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 157855744 18:29:19 INFO - 2015-12-15 18:29:19,166 DEBUG : BROWSER_OUTPUT: 18:29:21 INFO - 2015-12-15 18:29:21,113 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41349/tests/kraken/driver.html?testName=audio-oscillator) 18:29:21 INFO - 2015-12-15 18:29:21,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 155414528 18:29:21 INFO - 2015-12-15 18:29:21,682 DEBUG : BROWSER_OUTPUT: 18:29:23 INFO - 2015-12-15 18:29:23,796 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41349/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:29:24 INFO - 2015-12-15 18:29:24,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 157351936 18:29:24 INFO - 2015-12-15 18:29:24,357 DEBUG : BROWSER_OUTPUT: 18:29:27 INFO - 2015-12-15 18:29:27,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41349/tests/kraken/driver.html?testName=imaging-darkroom) 18:29:27 INFO - 2015-12-15 18:29:27,749 DEBUG : BROWSER_OUTPUT: RSS: Main: 157278208 18:29:27 INFO - 2015-12-15 18:29:27,749 DEBUG : BROWSER_OUTPUT: 18:29:31 INFO - 2015-12-15 18:29:31,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41349/tests/kraken/driver.html?testName=imaging-desaturate) 18:29:31 INFO - 2015-12-15 18:29:31,647 DEBUG : BROWSER_OUTPUT: RSS: Main: 158298112 18:29:31 INFO - 2015-12-15 18:29:31,647 DEBUG : BROWSER_OUTPUT: 18:29:34 INFO - 2015-12-15 18:29:34,379 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41349/tests/kraken/driver.html?testName=json-parse-financial) 18:29:34 INFO - 2015-12-15 18:29:34,948 DEBUG : BROWSER_OUTPUT: RSS: Main: 156782592 18:29:34 INFO - 2015-12-15 18:29:34,948 DEBUG : BROWSER_OUTPUT: 18:29:36 INFO - 2015-12-15 18:29:36,457 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41349/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:29:37 INFO - 2015-12-15 18:29:37,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 164610048 18:29:37 INFO - 2015-12-15 18:29:37,023 DEBUG : BROWSER_OUTPUT: 18:29:38 INFO - 2015-12-15 18:29:38,761 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:29:39 INFO - 2015-12-15 18:29:39,363 DEBUG : BROWSER_OUTPUT: RSS: Main: 162856960 18:29:39 INFO - 2015-12-15 18:29:39,363 DEBUG : BROWSER_OUTPUT: 18:29:41 INFO - 2015-12-15 18:29:41,314 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:29:41 INFO - 2015-12-15 18:29:41,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 167919616 18:29:41 INFO - 2015-12-15 18:29:41,875 DEBUG : BROWSER_OUTPUT: 18:29:44 INFO - 2015-12-15 18:29:44,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:29:44 INFO - 2015-12-15 18:29:44,756 DEBUG : BROWSER_OUTPUT: RSS: Main: 174743552 18:29:44 INFO - 2015-12-15 18:29:44,756 DEBUG : BROWSER_OUTPUT: 18:29:47 INFO - 2015-12-15 18:29:47,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:29:47 INFO - 2015-12-15 18:29:47,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 170819584 18:29:47 INFO - 2015-12-15 18:29:47,741 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,378 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41349/tests/kraken/driver.html?testName=ai-astar) 18:29:49 INFO - 2015-12-15 18:29:49,636 DEBUG : BROWSER_OUTPUT: RSS: Main: 229040128 18:29:49 INFO - 2015-12-15 18:29:49,636 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,637 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:29:49 INFO - 2015-12-15 18:29:49,637 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:29:49 INFO - 2015-12-15 18:29:49,637 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:29:49 INFO - 2015-12-15 18:29:49,637 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:29:49 INFO - 2015-12-15 18:29:49,637 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;103;110;109;109;102;110;109;99 18:29:49 INFO - 2015-12-15 18:29:49,637 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;122;156;161;123;142;147;125;163;122 18:29:49 INFO - 2015-12-15 18:29:49,637 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;159;162;162;160;162;160;160;159 18:29:49 INFO - 2015-12-15 18:29:49,638 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;144;73;72;75;71;148;71 18:29:49 INFO - 2015-12-15 18:29:49,638 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;132;89;90;89;132;89;90;133 18:29:49 INFO - 2015-12-15 18:29:49,638 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;109;109;110;110;109;109 18:29:49 INFO - 2015-12-15 18:29:49,638 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;144;145;144;145;146;145;145;145;146 18:29:49 INFO - 2015-12-15 18:29:49,638 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;95;96;96;95;95 18:29:49 INFO - 2015-12-15 18:29:49,638 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;56;57;57;57;60;58;57;53;55 18:29:49 INFO - 2015-12-15 18:29:49,638 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;64;65;64;63;65;64;64 18:29:49 INFO - 2015-12-15 18:29:49,639 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;76;79;79;79;78;78;78;78;77 18:29:49 INFO - 2015-12-15 18:29:49,639 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;114;112;105;117;112;116;107;111;116 18:29:49 INFO - 2015-12-15 18:29:49,639 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;138;137;136;128;135;138;135;136;136 18:29:49 INFO - 2015-12-15 18:29:49,639 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;64;63;63;62;62;64;62;62;62 18:29:49 INFO - 2015-12-15 18:29:49,639 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:29:49 INFO - 2015-12-15 18:29:49,640 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:29:49 INFO - 2015-12-15 18:29:49,640 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,888 18:29:49 INFO - 2015-12-15 18:29:49,640 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:29:49 INFO - 2015-12-15 18:29:49,640 DEBUG : BROWSER_OUTPUT: __startTimestamp1450232989631__endTimestamp 18:29:49 INFO - 2015-12-15 18:29:49,640 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,640 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:29:49 INFO - 2015-12-15 18:29:49,641 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:29:49 INFO - 2015-12-15 18:29:49,641 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,641 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.90 Median:109.50 stddev:4.01 (3.7%) stddev-sans-first:4.24 18:29:49 INFO - 2015-12-15 18:29:49,641 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 103.0 110.0 109.0 109.0 102.0 110.0 109.0 99.0 18:29:49 INFO - 2015-12-15 18:29:49,641 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,641 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.70 Median:146.50 stddev:16.57 (11.3%) stddev-sans-first:17.47 18:29:49 INFO - 2015-12-15 18:29:49,642 DEBUG : BROWSER_OUTPUT: Values: 146.0 122.0 156.0 161.0 123.0 142.0 147.0 125.0 163.0 122.0 18:29:49 INFO - 2015-12-15 18:29:49,642 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.17 (0.7%) stddev-sans-first:1.24 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 159.0 162.0 162.0 160.0 162.0 160.0 160.0 159.0 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.90 Median:72.00 stddev:31.19 (43.3%) stddev-sans-first:32.54 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 144.0 73.0 72.0 75.0 71.0 148.0 71.0 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.40 Median:89.50 stddev:20.66 (23.1%) stddev-sans-first:21.42 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 132.0 89.0 90.0 89.0 132.0 89.0 90.0 133.0 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 109.0 109.0 18:29:49 INFO - 2015-12-15 18:29:49,643 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: Values: 145.0 144.0 145.0 144.0 145.0 146.0 145.0 145.0 145.0 146.0 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.83 (3.2%) stddev-sans-first:1.94 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: Values: 57.0 56.0 57.0 57.0 57.0 60.0 58.0 57.0 53.0 55.0 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 64.0 65.0 64.0 63.0 65.0 64.0 64.0 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:0.94 (1.2%) stddev-sans-first:1.00 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: Values: 78.0 76.0 79.0 79.0 79.0 78.0 78.0 78.0 78.0 77.0 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,644 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.90 Median:115.00 stddev:4.43 (3.9%) stddev-sans-first:4.12 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: Values: 119.0 114.0 112.0 105.0 117.0 112.0 116.0 107.0 111.0 116.0 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.90 Median:136.50 stddev:3.18 (2.3%) stddev-sans-first:3.00 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: Values: 140.0 138.0 137.0 136.0 128.0 135.0 138.0 135.0 136.0 136.0 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 63.0 63.0 62.0 62.0 64.0 62.0 62.0 62.0 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:29:49 INFO - 2015-12-15 18:29:49,645 DEBUG : BROWSER_OUTPUT: 18:29:50 INFO - 2015-12-15 18:29:50,321 INFO : Browser exited with error code: 0 18:29:50 INFO - 2015-12-15 18:29:50,375 INFO : Completed test kraken (00:00:57) 18:29:50 INFO - 2015-12-15 18:29:50,375 INFO : Starting test v8_7 18:29:50 INFO - 2015-12-15 18:29:50,375 DEBUG : operating with platform_type : linux_ 18:29:50 INFO - 2015-12-15 18:29:50,375 INFO : Initialising browser for v8_7 test... 18:29:50 INFO - 2015-12-15 18:29:50,381 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphGzzQQ/profile http://localhost:41349/getInfo.html 18:29:53 INFO - 2015-12-15 18:29:53,873 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:29:53 INFO - 2015-12-15 18:29:53,874 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:29:53 INFO - 2015-12-15 18:29:53,874 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:29:53 INFO - 2015-12-15 18:29:53,874 DEBUG : BROWSER_OUTPUT: __metrics 18:29:54 INFO - 2015-12-15 18:29:54,002 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:29:54 INFO - 2015-12-15 18:29:54,951 INFO : Browser initialized. 18:29:54 INFO - 2015-12-15 18:29:54,952 INFO : Running cycle 1/1 for v8_7 test... 18:29:54 INFO - 2015-12-15 18:29:54,952 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphGzzQQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:30:06 INFO - 2015-12-15 18:30:06,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 131100672 18:30:06 INFO - 2015-12-15 18:30:06,620 DEBUG : BROWSER_OUTPUT: 18:30:27 INFO - 2015-12-15 18:30:27,958 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41349/tests/v8_7/run.html (next: http://localhost:41349/tests/v8_7/run.html) 18:30:28 INFO - 2015-12-15 18:30:28,208 DEBUG : BROWSER_OUTPUT: RSS: Main: 415031296 18:30:28 INFO - 2015-12-15 18:30:28,209 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,209 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:30:28 INFO - 2015-12-15 18:30:28,209 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:30:28 INFO - 2015-12-15 18:30:28,209 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:30:28 INFO - 2015-12-15 18:30:28,209 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:30:28 INFO - 2015-12-15 18:30:28,210 DEBUG : BROWSER_OUTPUT: |0;Richards;163.05233980107616 18:30:28 INFO - 2015-12-15 18:30:28,210 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;166.27868307283006 18:30:28 INFO - 2015-12-15 18:30:28,210 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.8988764044944 18:30:28 INFO - 2015-12-15 18:30:28,210 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5122.448979591837 18:30:28 INFO - 2015-12-15 18:30:28,211 DEBUG : BROWSER_OUTPUT: |4;RayTrace;943.3962264150944 18:30:28 INFO - 2015-12-15 18:30:28,211 DEBUG : BROWSER_OUTPUT: |5;Earley;552.791597567717 18:30:28 INFO - 2015-12-15 18:30:28,212 DEBUG : BROWSER_OUTPUT: |6;Boyer;10458.333333333334 18:30:28 INFO - 2015-12-15 18:30:28,212 DEBUG : BROWSER_OUTPUT: |7;RegExp;24487.80487804878 18:30:28 INFO - 2015-12-15 18:30:28,212 DEBUG : BROWSER_OUTPUT: |8;Splay;528.5412262156448 18:30:28 INFO - 2015-12-15 18:30:28,212 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4817.307692307692 18:30:28 INFO - 2015-12-15 18:30:28,212 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:30:28 INFO - 2015-12-15 18:30:28,212 DEBUG : BROWSER_OUTPUT: __startTimestamp1450233028208__endTimestamp 18:30:28 INFO - 2015-12-15 18:30:28,212 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,213 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:30:28 INFO - 2015-12-15 18:30:28,213 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:30:28 INFO - 2015-12-15 18:30:28,213 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,213 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.05 Median:163.05 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,213 DEBUG : BROWSER_OUTPUT: Values: 163.1 18:30:28 INFO - 2015-12-15 18:30:28,213 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,213 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:166.28 Median:166.28 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,214 DEBUG : BROWSER_OUTPUT: Values: 166.3 18:30:28 INFO - 2015-12-15 18:30:28,214 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,214 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.90 Median:280.90 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,214 DEBUG : BROWSER_OUTPUT: Values: 280.9 18:30:28 INFO - 2015-12-15 18:30:28,214 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,214 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5122.45 Median:5122.45 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,214 DEBUG : BROWSER_OUTPUT: Values: 5122.4 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:943.40 Median:943.40 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: Values: 943.4 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:552.79 Median:552.79 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: Values: 552.8 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,215 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10458.33 Median:10458.33 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,216 DEBUG : BROWSER_OUTPUT: Values: 10458.3 18:30:28 INFO - 2015-12-15 18:30:28,216 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,216 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24487.80 Median:24487.80 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,216 DEBUG : BROWSER_OUTPUT: Values: 24487.8 18:30:28 INFO - 2015-12-15 18:30:28,216 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,216 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:528.54 Median:528.54 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,216 DEBUG : BROWSER_OUTPUT: Values: 528.5 18:30:28 INFO - 2015-12-15 18:30:28,217 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,217 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 18:30:28 INFO - 2015-12-15 18:30:28,217 DEBUG : BROWSER_OUTPUT: Values: 4817.3 18:30:28 INFO - 2015-12-15 18:30:28,217 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:30:28 INFO - 2015-12-15 18:30:28,217 DEBUG : BROWSER_OUTPUT: 18:30:28 INFO - 2015-12-15 18:30:28,833 INFO : Browser exited with error code: 0 18:30:28 INFO - 2015-12-15 18:30:28,848 INFO : Completed test v8_7 (00:00:38) 18:30:29 INFO - 2015-12-15 18:30:29,258 INFO : Completed test suite (00:20:05) 18:30:29 INFO - 2015-12-15 18:30:29,258 DEBUG : Working with test: dromaeo_css 18:30:29 INFO - 2015-12-15 18:30:29,258 DEBUG : Generating results file: dromaeo_css 18:30:29 INFO - 2015-12-15 18:30:29,260 DEBUG : Working with test: kraken 18:30:29 INFO - 2015-12-15 18:30:29,260 DEBUG : Generating results file: kraken 18:30:29 INFO - 2015-12-15 18:30:29,260 INFO : javascript benchmark 18:30:29 INFO - 2015-12-15 18:30:29,260 DEBUG : Working with test: v8_7 18:30:29 INFO - 2015-12-15 18:30:29,261 DEBUG : Generating results file: v8_7 18:30:29 INFO - 2015-12-15 18:30:29,261 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:30:29 INFO - 2015-12-15 18:30:29,348 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 18:30:29 INFO - 2015-12-15 18:30:29,348 DEBUG : process_Request line: dromaeo_css 7260.77 graph.html#tests=[[72,53,35]] 18:30:29 INFO - 2015-12-15 18:30:29,348 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:30:29 INFO - 2015-12-15 18:30:29,479 DEBUG : process_Request line: kraken 1457.40 graph.html#tests=[[232,53,35]] 18:30:29 INFO - 2015-12-15 18:30:29,479 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:30:29 INFO - 2015-12-15 18:30:29,623 DEBUG : process_Request line: v8_7 23287.31 graph.html#tests=[[230,53,35]] 18:30:29 INFO - 2015-12-15 18:30:29,624 INFO : javascript benchmark 18:30:29 INFO - 2015-12-15 18:30:29,628 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3258.5763850699414, 3379.966200337996, 3401.4387626041703, 3402.7958322500635, 3432.5537680101584, 5544.223808666785, 5617.185508101323, 5632.464915832995, 5637.154426835977, 5650.463205995434, 5654.264945557082, 5677.716114194294, 5679.148127780835, 5696.971515142429, 5698.971505142468, 4938.38270216223, 5108.2848401223855, 5151.6908985461, 5208.374995000586, 5242.475752424765, 5005.57452616528, 5082.949170508285, 5087.364079490064, 5112.769925353368, 5120.513551212647, 5106.387233531963, 5117.692938423688, 5131.871703207413, 5196.168613021917, 5198.298229738974, 5081.796728130888, 5102.6173037022, 5166.0, 5170.991656626959, 5184.844454666347, 4860.198067318888, 4878.170710979142, 4959.2809042688605, 5068.645194836363, 5080.034793389244, 5399.460053994609, 5400.108982018001, 5402.135658294654, 5428.267183930158, 5459.153831156177, 6042.274927008743, 6043.7280322385595, 6044.123602077674, 6051.152838602599, 6052.9697351513405, 5879.412058794129, 5993.61041532299, 5995.190649262381, 6090.725917333731, 6123.418275263843, 5749.281339832479, 5749.626274292157, 5751.942480575224, 5754.338251101135, 5787.305523337184, 5782.2194003808945, 6234.470070044006, 6240.594361366497, 6242.500599951993, 6252.218472690914, 5888.116782482662, 5888.9705551472825, 6009.519238460913, 6014.308354539196, 6046.758129674852, 6091.878162436727, 6091.908621370683, 6097.664628445478, 6099.054646529795, 6102.7558897644485, 5747.39652336507, 5817.650940943557, 6058.454739073504, 6064.393560643988, 6069.514438844882, 6027.78902738402, 6130.892302157565, 6131.468419844907, 6138.754449822047, 6139.6009259398, 4670.719756814534, 4672.369230153886, 4673.065386922629, 4681.344611754357, 4697.271922851964, 3999.7600143991453, 4043.332850079704, 4072.857449989236, 4180.979095104505, 4208.873733787991, 5685.374608793029, 5690.800821971294, 5697.857553561194, 5699.487046165865, 5776.826695199151, 5981.970090149522, 5985.191999080155, 5986.640801551921, 5987.730552125165, 6021.698915054318, 5601.075822489325, 5666.2350582670815, 5733.88532229353, 5766.94233057664, 5836.88326233473, 5253.159494480865, 5283.0, 5284.101702710548, 5285.68285902847, 5289.074411977888, 6234.594751341239, 6249.875002499924, 6251.437370636573, 6252.062190671436, 6275.309715931244, 5896.410358964155, 5992.280926288873, 6040.0, 6110.633361998294, 6122.938051683444, 6333.65164915934, 6335.334789847184, 6338.176037115146, 6353.618782873043, 6356.523260755369, 5680.914786278292, 6038.5772995889865, 6039.396060393926, 6053.878922421615, 6053.909191362045, 5852.561057920588, 5989.520838332835, 6107.037429747615, 6107.75279946992, 6139.785107521216, 5962.165296858357, 5964.522838172849, 5965.254343207099, 5968.820935371946, 5995.820125396245, 5643.35101463337, 5712.314522257189, 5958.851028724316, 5975.641461512323, 5975.970120149545, 5680.602357834912, 5745.91381129275, 5748.223989761496, 5752.626079304831, 5791.7683292667825, 5933.199018132497, 5937.376575459514, 5945.613535120204, 5998.430149135826, 5999.100134979788, 5564.137558678412, 5647.915281270702, 5684.175794509691, 5816.854578635568, 5837.766489340379], "unit": "ms", "name": "dojo.html", "value": 5565.4750962409225}, {"lowerIsBetter": false, "replicates": [607.7720854679495, 644.1658052821598, 649.9545031847773, 655.839319366755, 662.82435154684, 35282.647173528254, 42766.78616606916, 43151.99999999996, 43184.78407607965, 43218.56781432185, 47463.288050679264, 48232.0, 48232.0, 48257.75871120648, 48294.51705482944, 8373.246407823295, 8444.662213511452, 9188.954055229715, 9351.812963740755, 9358.157765801077, 11087.168462365315, 11260.380679062648, 11260.54957801687, 11317.547298108066, 11330.263532870336, 11095.944520277388, 11219.214654974156, 11224.214304998653, 11230.943845280763, 11235.606753763619, 11174.553017879274, 11248.0, 11270.042046426064, 11274.774504509945, 11275.492602832892, 11037.227394082418, 11171.106311495061, 11186.384748838811, 11191.776164476665, 11198.216124871262, 43905.34141987873, 44056.339154912705, 44094.33858492125, 44111.132776369435, 44135.55864441347, 3338.21551935295, 3415.043787739412, 3450.4996775467416, 3498.8250587470525, 3511.613722490524, 30169.0, 30387.544186837215, 30509.0, 30511.69488305133, 30637.540436893465, 3891.9221615567812, 3921.1961547882615, 3999.1801680655394, 4049.696272779524, 4074.5314288856866, 303686.0, 304778.0, 304823.0, 305168.47415763, 305451.0, 61135.388646112966, 62129.0, 68049.3195068043, 75918.0, 76347.0, 70226.64886675533, 70340.2965970344, 70386.296137039, 70509.0, 70574.64712676506, 17233.827661723473, 17352.47942561725, 17374.0, 17469.65060698779, 17475.126243687766, 13040.739185216242, 13058.934705326412, 13206.801897971538, 13224.537141200011, 13226.735465290833, 22418.55162896733, 22532.0, 22702.0, 22724.09103635869, 22806.885965570065, 7855.607219638995, 8138.674453021813, 8153.5923203839575, 8182.631781569844, 8290.0, 954.7326748510428, 1001.62439085343, 1051.3113910388709, 1124.7413094988199, 1125.3810404277779, 30733.38533229323, 31240.0, 31313.06060818179, 31912.521312180335, 31982.520262196085, 1080.1358912869673, 1091.8908109189176, 1123.971900702489, 1165.3823473559028, 1180.7579446213506, 14250.429982800779, 14827.258637068104, 14911.627209319637, 15165.772513412308, 16584.336626535045, 286877.0, 289116.0, 289691.0, 290810.0, 291079.0, 54510.0, 54650.453495464535, 54832.72583637056, 54994.72502637461, 55083.0, 1430.8283006039135, 1432.1550285331705, 1451.078565111141, 1453.9563813085624, 1458.8103546538882, 30463.238419039702, 30483.2379190522, 30898.53652195174, 31271.84364078165, 31709.5243571342, 49731.75134124306, 51005.234921475465, 52520.0, 52936.20595690991, 56820.0, 256252.0, 261817.0, 263266.0, 263996.0, 264107.0, 37414.62585374111, 37889.62110378861, 38069.4289585662, 38087.61912380951, 38107.23785524329, 49324.75337623289, 50048.24927626162, 50635.74682126566, 50888.49111508838, 51958.220626691385, 41811.581884181986, 42071.7896410516, 42609.36085958651, 43115.56884431116, 43845.0, 42758.358624620036, 42910.0, 43878.561214387446, 47445.762771185924, 48087.27869081897, 43224.56775432205, 44263.336049958634, 44277.11445771002, 44366.556334436245, 44560.77719611381, 37246.0, 38264.80867595644, 38783.612163878, 38962.220755585295, 39257.21485570216, 28854.711452886044, 29210.0, 29258.7074129256, 29282.56076158902, 29565.85217073901], "unit": "ms", "name": "ext.html", "value": 20633.204934571182}, {"lowerIsBetter": false, "replicates": [389.2545774841181, 390.6757391365167, 395.7486995757693, 396.3499860229224, 397.98607048753297, 14063.0, 18091.27634894604, 19994.900025499872, 20017.59964800705, 20986.160553577876, 17097.40159094432, 17134.31462741492, 19406.0, 19435.902820485913, 19462.221511139574, 5764.769409223626, 5849.736761845706, 5879.294484661847, 5888.175655408247, 5898.616589921663, 5778.364379918226, 5858.062709966406, 5923.792667256647, 5926.199963004982, 5943.554233432488, 4424.690271680984, 4544.8863778405475, 5998.4001599839885, 6005.369436209201, 6010.549208809335, 6348.460380867632, 6462.709178086998, 6512.251091124534, 6512.348765123474, 6514.153160089182, 5703.62926409782, 5816.447437493432, 5819.185314056035, 5819.970900145515, 5821.417858214187, 23260.0, 23651.29046128602, 24097.277081687578, 24107.39731506709, 24421.633675494882, 737.9963100184518, 743.7657138001513, 743.773149189497, 2188.8248940084914, 2288.1994144842743, 14383.993120481571, 14749.926250368679, 14769.113853168845, 15081.698366032728, 15144.242787860563, 1924.5862139639905, 1936.912838922252, 1939.0, 1983.4545499987476, 2030.6547886859269, 68827.0, 69098.65450672715, 70242.0, 70872.29127708709, 71088.0, 11709.297442153498, 12118.697032574257, 12222.0, 12229.877701223051, 12247.938760306142, 16432.507024789276, 16434.342626295052, 16441.506754797374, 16453.0, 16508.42220522276, 8799.428037177562, 8859.512726800089, 8860.512671802984, 8874.112588741204, 8901.73294801157, 8316.459430137022, 8327.458715183615, 8356.28971537414, 8407.41148119638, 8441.95779021101, 6975.128108986377, 6988.266232045562, 7058.188308344504, 7071.823204419828, 7083.150021997393, 1268.8604253532103, 1311.6327428320085, 1788.9313514589555, 2053.9897300513403, 2112.5246819465497, 498.27252211770497, 519.4727351737989, 541.6858222231097, 564.6470955652717, 580.7851095094758, 12856.100072994988, 13037.804432933513, 13525.729485410237, 13649.385777640031, 13689.863101369057, 567.1634339349426, 570.6490508337342, 579.1313030454319, 585.1427658480346, 597.4264705882315, 6741.696623651947, 7061.470389720792, 7069.646517674096, 7117.6085315308155, 7336.082989626297, 391565.0, 408157.0, 519210.0, 522251.0, 561911.0, 85237.0, 88445.0, 89095.55452222827, 90406.0, 90641.0, 536.7450461031057, 548.0, 549.8872731090117, 550.7026205848797, 550.713628912971, 11354.489047992694, 11527.596534121265, 11685.0, 11919.582814601446, 11982.70043248891, 39259.0, 39448.802755986035, 39588.80205599069, 40039.59960400359, 40224.597754022085, 51988.220176696625, 52069.0, 54469.72765136149, 54678.453215467336, 54803.0, 9393.201577865959, 9553.0, 9635.903640963501, 9662.0, 9664.661736839176, 13372.0, 13876.930615347263, 13886.305684715926, 14080.436782528584, 14099.859001410265, 11865.466054027416, 12076.75846483083, 12342.0, 12370.567030153901, 12381.380930953597, 11886.227395219628, 11891.524339026342, 12032.819507707567, 12045.51817927273, 12131.878681213075, 12121.575744848888, 12436.129470936947, 12494.875051249372, 12919.935400323315, 13107.213567186, 9106.0, 9609.855852162083, 9893.50532473359, 9917.256205784452, 9926.503674816375, 9553.952230239081, 11906.464209110438, 12046.939765301116, 12201.633950981484, 12290.20113692607], "unit": "ms", "name": "jquery.html", "value": 8643.22619895534}, {"lowerIsBetter": false, "replicates": [1571.8663913567345, 1610.2190437637737, 1650.2656317938515, 1831.8809277396967, 1913.4546654203555, 7907.4069444791585, 8284.378671599632, 8285.875711864326, 8289.295409890166, 8294.75115746527, 7971.083325417579, 7996.080450748165, 7997.960010199956, 8013.398995075364, 8019.759407217778, 2208.1277895231456, 2226.3432287475193, 2238.697775800262, 2241.540484200743, 2248.3592176229777, 2417.7340492545804, 2427.5751743444825, 2427.78149966503, 2428.659987601737, 2428.7206971198275, 1995.2118913029344, 2038.0523056778595, 2211.1266049910278, 2332.1720789119872, 2375.10933399975, 2223.83321250906, 2243.1924507177405, 2245.3376254005107, 2257.1874125314876, 2258.096761295487, 2373.703287089114, 2426.3812727754485, 2427.0898413095088, 2428.7206971198184, 2429.2226487524, 7003.719851205997, 7238.601876896769, 7646.808829779245, 7879.0, 7888.802779930547, 5009.5491405773655, 5085.74571271435, 5093.592512599021, 5094.057599344133, 5095.974520127413, 3336.59960804705, 3348.882789102394, 3352.8994130176134, 3395.2700169463683, 3445.0, 1075.3118004477142, 1087.6519513755602, 1138.4876805437586, 1141.5148561861258, 1149.919505634606, 11221.04621107207, 11245.493952772145, 11251.381174035425, 11257.0, 11258.887411125866, 4651.37206477128, 4652.232381657056, 4682.1338052460405, 4691.9530804692195, 4716.0, 9519.524023798782, 9519.571619277149, 9526.476043817518, 9528.475933823567, 9538.666146684833, 5183.844484665391, 5205.895882082337, 5220.504052115043, 5233.450487698737, 5233.555147812479, 4698.718076915396, 4705.882352941204, 4721.244600863845, 4723.7874295656775, 4724.00795832879, 3839.3665045267776, 3874.1864208516527, 3881.184951160288, 3899.2981263372867, 3904.1996390739832, 1262.2868079535033, 1266.2402558464848, 1270.0538099116218, 1270.3203785974458, 1283.9871601284055, 1086.3481910853425, 1100.862392200975, 1105.1214284643727, 1344.9865501344862, 1467.1783801071433, 7747.418943579141, 7814.960925195338, 7848.293653571206, 7857.096433910059, 7857.842843143105, 551.2695678226286, 554.1078863030517, 560.7672815781486, 561.6209058885236, 563.5294529068274, 5867.618604790675, 5889.293284805851, 6253.0, 6307.779227727099, 6319.494440444753, 9335.393199442151, 9470.100340467645, 9557.90442095584, 9558.378705384128, 9560.043995600385, 9325.440473571607, 9472.621095156255, 9485.288603354777, 9535.046495350409, 9589.184919281939, 7517.436192285601, 7621.542707437571, 7640.197779233212, 7644.847103057908, 7646.885296720554, 7583.203763604963, 7590.127135379502, 7760.84478310442, 7767.92232077672, 7774.0, 8735.126487351468, 8833.734987950373, 8903.19871211594, 8906.955465222632, 8938.10618938101, 8863.778405539913, 9050.09499050116, 9057.864132037894, 9079.364444488823, 9087.36388452802, 7768.262015108556, 7802.180771019188, 7906.0, 7921.207879212033, 7925.960370198112, 7815.570143642154, 7867.488613240121, 7870.685172593032, 7877.566733829694, 7894.407919406182, 7321.78034658961, 7440.813979650552, 7490.101187857492, 7608.201138880338, 7675.769726908202, 7239.131304243525, 7437.256274372519, 7476.514026588255, 7484.438667100098, 7622.58075805836, 7297.306755858185, 7336.449766267658, 7346.5592064476305, 7351.595662238628, 7357.926420735724, 6961.582305061713, 6968.059311992816, 6970.372666460043, 6988.231294557696, 6998.0, 6822.931770682229, 6970.23327433992, 6970.895436568354, 7029.5430796998035, 7076.610786406591], "unit": "ms", "name": "mootools.html", "value": 4652.536691592864}, {"lowerIsBetter": false, "replicates": [362.12727327141596, 363.3151509404771, 368.9981550092249, 369.3812863453716, 370.04712864374227, 5911.88176236475, 5948.553858460622, 5958.642481451105, 5969.791057312995, 5976.432238937299, 5710.400407957157, 5714.485696287334, 5820.039693450575, 5837.445442682938, 5855.590108692393, 4612.1236964976715, 4614.584687378136, 4620.214563524192, 4629.953700462986, 4636.211843986522, 4620.8151673933, 4621.2837010263465, 4632.119897219534, 4636.420447444069, 4640.025594625133, 4590.449146102473, 4591.311303304515, 4592.334111553822, 4600.5629465200755, 4605.102005109005, 4585.2663573828195, 4658.045100754337, 4674.392328997243, 4675.602573781232, 4689.7655117244, 4175.958240417587, 4176.436181115541, 4184.476940382452, 4185.22573323936, 4186.037211441355, 5674.659520428746, 5683.0, 5690.687012214326, 5696.401877802854, 5702.0021496238, 5285.976073062108, 5408.945910540883, 5412.810551630674, 5415.296011518517, 5417.404085550586, 6146.231721034871, 6201.844953876144, 6208.41020103094, 6225.377462253784, 6231.937680623181, 4540.114677637862, 4540.18276710192, 4545.11370282795, 4546.47715512717, 4546.568076032771, 6416.00551914454, 6417.871642567169, 6425.357464253584, 6425.389587989133, 6430.7106180222, 4615.076984603093, 4667.206574882278, 4670.0, 4738.597219236334, 4744.0, 4725.740084295328, 4750.192467280571, 4796.136695394863, 4797.712137271775, 4798.904021919542, 5335.039692855324, 5335.359756829128, 5348.786048558092, 5349.1976203569775, 5353.9732301338245, 6263.6241825490615, 6299.307076221612, 6308.873822523524, 6309.589876658094, 6311.368863113652, 1653.3221379234697, 1689.2145152504145, 1691.6109294862251, 1697.5331783759318, 1708.086173896959, 1075.8117329467311, 1116.7487315354142, 1137.3289759042204, 1197.3414621957888, 1202.1584890576632, 5879.294484661782, 5895.322037965603, 5908.291005079418, 5912.793052243151, 5923.318818335946, 6277.843053923597, 6334.714937827809, 6344.460720838781, 6355.332690067568, 6361.141245931833, 5862.14998825168, 5976.073708575178, 5978.163057172, 5983.491403230687, 5987.042073268256, 3528.558930133733, 3568.518250036212, 3568.553930758655, 3572.982135089308, 3574.0, 6583.7695680651905, 6603.537752357385, 6613.966930165415, 6619.238787539494, 6695.933040669531, 6586.440152586988, 6594.934050659528, 6598.703058362289, 6600.966995165089, 6605.273419923805, 6544.345565443417, 6642.535022548472, 6642.767503137367, 6643.269240383554, 6647.268800431949, 6394.648294343856, 6416.903746443901, 6420.678966051585, 6425.036244563353, 6425.710843011982, 6323.272823625342, 6365.586236894587, 6374.776882809079, 6375.0, 6378.936210637834, 6603.834904127435, 6682.665866706548, 6714.127163468718, 6721.731130754715, 6728.495362847708, 4543.931841022321, 4580.152671755736, 4582.358469814162, 4583.083383323282, 4585.839495617637, 6369.01280301554, 6390.4248617624635, 6413.262474815456, 6415.326390729093, 6434.903476447764, 6210.472109870711, 6221.96889015552, 6272.247330320391, 6281.309056003927, 6282.905756413566, 6256.937430625636, 6261.686915654108, 6262.937370626418, 6263.655498947602, 6268.561200716089, 6038.094285857156, 6110.7555697771595, 6113.113598528277, 6121.3572574879, 6129.325774164749, 5987.820365389045, 6038.486728628116, 6180.598261113013, 6276.121343012073, 6278.277998030102, 6059.575829691879, 6081.483073938764, 6090.1169330445955, 6094.451499365078, 6101.267847858286], "unit": "ms", "name": "prototype.html", "value": 4795.18607568739}, {"lowerIsBetter": false, "replicates": [919.1643778941963, 958.6117622362946, 959.1607343574373, 962.93259471837, 981.9803603927917, 13937.651558711024, 14241.71516569668, 17210.91394543027, 17307.22117504712, 17355.132243387845, 14817.0, 14885.0, 14893.0, 14909.478168264113, 14910.10539367636, 744.5867543513348, 765.6439755513685, 896.9461832290051, 907.2197909797585, 921.4010892919622, 906.8866391701038, 907.2016625369698, 911.6900253913668, 913.2785099771173, 913.5386629751966, 942.9198518125934, 948.4119845695644, 948.5684013773716, 954.1555723184962, 954.6610953111649, 771.5949126708467, 798.6925033861947, 928.058021108572, 961.0149596663476, 963.2390411574918, 702.6170736948325, 704.4118161335291, 705.2806137739477, 705.4744215559441, 706.0820932787356, 14321.856781432156, 14322.498712545012, 14366.928165359212, 14440.0, 14452.349644266033, 10402.479876006246, 10940.288881222696, 11011.61459348919, 11066.114710823114, 11127.443627818657, 9889.357191782512, 10552.841707374395, 10601.416922069284, 10737.731556711067, 10907.672769816918, 3345.6988871001727, 3382.6279109297957, 3400.472926696366, 3403.829808509565, 3404.676555727202, 49474.25788613174, 49793.751031244974, 49817.0, 50305.0, 51511.742441287926, 7704.537727736354, 7815.4529182957785, 7820.7262745804765, 7830.177831327743, 7939.0870049945, 12639.304838233986, 12652.557160499338, 12664.303463309423, 12707.237565746085, 12735.745285094246, 6961.860762784716, 7078.858422831515, 7119.537230080028, 7147.749828755968, 7244.130704315516, 4517.41273634432, 4584.9541504585195, 4723.834665786681, 4815.542523460265, 4833.73414462208, 2951.409718056397, 2975.8363290019256, 2982.776291778125, 2983.8508074596616, 2985.11938978002, 448.1328629102697, 448.6119506626744, 462.9213033784265, 470.63290633306076, 471.4389876047561, 599.8500374906273, 608.4888693497484, 654.201873714068, 699.9720011199597, 798.9800254993555, 9195.632174712937, 9687.17658998979, 9762.121409073217, 9787.90212097884, 9832.065953734384, 597.3996133885504, 745.6334649059501, 758.9013428254402, 764.4495962906698, 773.6170595555236, 4226.682998775104, 4535.916382405304, 4604.608608268268, 4695.2252878275385, 4808.831690890802, 60236.096458553155, 60642.0, 65420.67289663521, 65463.34536654668, 65648.6717566409, 54272.45727542674, 55974.0, 56260.43739562552, 59217.0, 59532.404675953556, 52111.478885210665, 52638.0, 52916.470835291926, 53045.469545304055, 53424.0, 9520.285978551497, 9559.044095590385, 9834.508274586386, 9879.308448408543, 9883.456409897524, 38508.0, 39413.0, 39542.802285989535, 40436.59563404328, 42748.78625606852, 50724.74637626788, 51331.48668513267, 51829.481705182465, 52112.0, 52115.73942130265, 8673.913260867563, 8751.299896008431, 8787.824243514966, 8939.106089391269, 9143.542822858963, 13144.60566183016, 13241.933790330986, 13505.12216705911, 13523.661908452368, 14054.718905622034, 12294.938525307316, 12331.815022774845, 12427.93786031064, 12429.937850310691, 12451.75096498083, 12818.807717884425, 13097.541586044443, 13139.211647301192, 13191.67020824487, 13326.866731332562, 12900.225986440844, 12925.806112908502, 12949.482020719443, 12984.805227921777, 13003.08978371505, 10898.891011089787, 11264.549418023187, 11384.316940983568, 11439.027682647069, 11451.198416111125, 10862.0, 10944.452777361259, 10946.945265273622, 11063.83404248953, 11131.332120072822], "unit": "ms", "name": "yui.html", "value": 6616.27122325612}], "name": "dromaeo_css", "value": 7260.762469190181}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 103.0, 110.0, 109.0, 109.0, 102.0, 110.0, 109.0, 99.0], "unit": "ms", "name": "ai-astar", "value": 106.9}, {"lowerIsBetter": true, "replicates": [146.0, 122.0, 156.0, 161.0, 123.0, 142.0, 147.0, 125.0, 163.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.7}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 159.0, 162.0, 162.0, 160.0, 162.0, 160.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 144.0, 73.0, 72.0, 75.0, 71.0, 148.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.9}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 132.0, 89.0, 90.0, 89.0, 132.0, 89.0, 90.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 102.4}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 144.0, 145.0, 144.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.0}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 57.0, 57.0, 57.0, 60.0, 58.0, 57.0, 53.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.7}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 63.0, 65.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [78.0, 76.0, 79.0, 79.0, 79.0, 78.0, 78.0, 78.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [119.0, 114.0, 112.0, 105.0, 117.0, 112.0, 116.0, 107.0, 111.0, 116.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.9}, {"lowerIsBetter": true, "replicates": [140.0, 138.0, 137.0, 136.0, 128.0, 135.0, 138.0, 135.0, 136.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 135.9}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 63.0, 63.0, 62.0, 62.0, 64.0, 62.0, 62.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.7}], "name": "kraken", "value": 1457.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.05233980107616], "unit": "score", "name": "Richards", "value": 216.50716599999996}, {"lowerIsBetter": false, "replicates": [166.27868307283006], "unit": "score", "name": "DeltaBlue", "value": 397.633652}, {"lowerIsBetter": false, "replicates": [280.8988764044944], "unit": "score", "name": "Encrypt", "value": 947.6043599999998}, {"lowerIsBetter": false, "replicates": [5122.448979591837], "unit": "score", "name": "Decrypt", "value": 51.96362151394425}, {"lowerIsBetter": false, "replicates": [943.3962264150944], "unit": "score", "name": "RayTrace", "value": 784.3883400000002}, {"lowerIsBetter": false, "replicates": [552.791597567717], "unit": "score", "name": "Earley", "value": 1205.631567}, {"lowerIsBetter": false, "replicates": [10458.333333333334], "unit": "score", "name": "Boyer", "value": 63.725545816733074}, {"lowerIsBetter": false, "replicates": [24487.80487804878], "unit": "score", "name": "RegExp", "value": 37.201578685258944}, {"lowerIsBetter": false, "replicates": [528.5412262156448], "unit": "score", "name": "Splay", "value": 154.18097200000005}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 23287.305957960405}]} 18:30:29 INFO - RETURN: dromaeo_css: 7260.77 18:30:29 INFO - RETURN: kraken: 1457.40 18:30:29 INFO - RETURN: v8_7: 23287.31 18:30:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23287.31"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1457.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7260.77"}}} 18:30:29 INFO - Return code: 0 18:30:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 18:30:29 INFO - # TBPL SUCCESS # 18:30:29 INFO - Running post-action listener: _resource_record_post_action 18:30:29 INFO - Running post-run listener: _resource_record_post_run 18:30:30 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 8192; Write bytes: 512319488; Read time: 60; Write time: 605712 18:30:30 INFO - install - Wall time: 14s; CPU: 16.0%; Read bytes: 0; Write bytes: 250335232; Read time: 0; Write time: 454480 18:30:30 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 8192; Write bytes: 261984256; Read time: 60; Write time: 151232 18:30:30 INFO - Running post-run listener: _upload_blobber_files 18:30:30 INFO - Blob upload gear active. 18:30:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:30:30 INFO - Copying logs to upload dir... 18:30:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1251.229026 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2015-12-15 18:30:30.583186) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 18:30:30.586574) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009973 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450155070/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 18:30:30.638311) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:30:30.638695) ========= 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=d7d9c069d1ebe471abe975fc000001d9-1450231084.53945-194331090 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 18:30:30.688605) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 18:30:30.688975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 18:30:30.689346) ========= ========= Total master_lag: 0.21 =========