builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1453397388.68 results: success (0) buildid: 20160121070721 builduid: 4acd06258fd44107ba4fa9f0cb53b611 revision: c018fbb65ecb505bc9e52fa617940ba8da5501b3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.678180) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.678677) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.678997) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005606 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.700836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.701253) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.714632) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.715039) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004561 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.764636) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.764940) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.765298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.765579) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-21 09:29:48-- 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% 63.4M=0s 2016-01-21 09:29:49 (63.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101383 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.879296) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.879625) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022872 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.930251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:48.930761) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c018fbb65ecb505bc9e52fa617940ba8da5501b3 --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-aurora --rev c018fbb65ecb505bc9e52fa617940ba8da5501b3 --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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-21 09:29:49,138 truncating revision to first 12 chars 2016-01-21 09:29:49,139 Setting DEBUG logging. 2016-01-21 09:29:49,139 attempt 1/10 2016-01-21 09:29:49,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c018fbb65ecb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-21 09:29:49,407 unpacking tar archive at: mozilla-aurora-c018fbb65ecb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.561989 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:49.506813) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:49.507161) ========= echo c018fbb65ecb505bc9e52fa617940ba8da5501b3 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'c018fbb65ecb505bc9e52fa617940ba8da5501b3'] 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False c018fbb65ecb505bc9e52fa617940ba8da5501b3 program finished with exit code 0 elapsedTime=0.004658 script_repo_revision: 'c018fbb65ecb505bc9e52fa617940ba8da5501b3' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:49.559797) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:49.560111) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:49.573351) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:49.573736) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/c018fbb65ecb505bc9e52fa617940ba8da5501b3 ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-21 09:29:49.574161) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-01-21 09:29:49.574472) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:29:49 INFO - MultiFileLogger online at 20160121 09:29:49 in /builds/slave/test-pgo 09:29:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:29:49 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:29:49 INFO - {'append_to_log': False, 09:29:49 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:29:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:29:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:29:49 INFO - 'branch': 'Mozilla-Aurora', 09:29:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:49 INFO - 'config_files': ('talos/linux_config.py',), 09:29:49 INFO - 'default_actions': ('clobber', 09:29:49 INFO - 'read-buildbot-config', 09:29:49 INFO - 'download-and-extract', 09:29:49 INFO - 'populate-webroot', 09:29:49 INFO - 'create-virtualenv', 09:29:49 INFO - 'install', 09:29:49 INFO - 'run-tests'), 09:29:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:49 INFO - 'download_minidump_stackwalk': True, 09:29:49 INFO - 'download_symbols': 'ondemand', 09:29:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:49 INFO - 'installer_path': 'installer.exe', 09:29:49 INFO - 'log_level': 'info', 09:29:49 INFO - 'log_name': 'talos', 09:29:49 INFO - 'log_to_console': True, 09:29:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:29:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:29:49 INFO - 'opt_config_files': (), 09:29:49 INFO - 'pip_index': False, 09:29:49 INFO - 'sps_profile': False, 09:29:49 INFO - 'sps_profile_interval': 0, 09:29:49 INFO - 'suite': 'dromaeojs', 09:29:49 INFO - 'system_bits': '32', 09:29:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:49 INFO - 'title': 'talos-linux64-ix-046', 09:29:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:49 INFO - 'use_talos_json': True, 09:29:49 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:29:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:49 INFO - 'work_dir': 'build'} 09:29:49 INFO - ##### 09:29:49 INFO - ##### Running clobber step. 09:29:49 INFO - ##### 09:29:49 INFO - Running pre-action listener: _resource_record_pre_action 09:29:49 INFO - Running main action method: clobber 09:29:49 INFO - rmtree: /builds/slave/test-pgo/build 09:29:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:29:50 INFO - Running post-action listener: _resource_record_post_action 09:29:50 INFO - ##### 09:29:50 INFO - ##### Running read-buildbot-config step. 09:29:50 INFO - ##### 09:29:50 INFO - Running pre-action listener: _resource_record_pre_action 09:29:50 INFO - Running main action method: read_buildbot_config 09:29:50 INFO - Using buildbot properties: 09:29:50 INFO - { 09:29:50 INFO - "properties": { 09:29:50 INFO - "buildnumber": 101, 09:29:50 INFO - "product": "firefox", 09:29:50 INFO - "script_repo_revision": "production", 09:29:50 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:29:50 INFO - "repository": "", 09:29:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:29:50 INFO - "buildid": "20160121070721", 09:29:50 INFO - "slavename": "talos-linux64-ix-046", 09:29:50 INFO - "pgo_build": "True", 09:29:50 INFO - "basedir": "/builds/slave/test-pgo", 09:29:50 INFO - "project": "", 09:29:50 INFO - "platform": "ubuntu64_hw", 09:29:50 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:29:50 INFO - "slavebuilddir": "test", 09:29:50 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:29:50 INFO - "branch": "mozilla-aurora", 09:29:50 INFO - "repo_path": "releases/mozilla-aurora", 09:29:50 INFO - "stage_platform": "linux64-pgo", 09:29:50 INFO - "builduid": "4acd06258fd44107ba4fa9f0cb53b611", 09:29:50 INFO - "revision": "c018fbb65ecb505bc9e52fa617940ba8da5501b3" 09:29:50 INFO - }, 09:29:50 INFO - "sourcestamp": { 09:29:50 INFO - "repository": "", 09:29:50 INFO - "hasPatch": false, 09:29:50 INFO - "project": "", 09:29:50 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:29:50 INFO - "changes": [ 09:29:50 INFO - { 09:29:50 INFO - "category": null, 09:29:50 INFO - "files": [ 09:29:50 INFO - { 09:29:50 INFO - "url": null, 09:29:50 INFO - "name": "https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 09:29:50 INFO - } 09:29:50 INFO - ], 09:29:50 INFO - "repository": "", 09:29:50 INFO - "rev": "c018fbb65ecb505bc9e52fa617940ba8da5501b3", 09:29:50 INFO - "who": "eakhgari@mozilla.com", 09:29:50 INFO - "when": 1453397072, 09:29:50 INFO - "number": 7493987, 09:29:50 INFO - "comments": "Bug 1237455 P5 Set headers on fetch() redirects. r=ehsan a=sylvestre\n", 09:29:50 INFO - "project": "", 09:29:50 INFO - "at": "Thu 21 Jan 2016 09:24:32", 09:29:50 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:29:50 INFO - "revlink": "", 09:29:50 INFO - "properties": [ 09:29:50 INFO - [ 09:29:50 INFO - "buildid", 09:29:50 INFO - "20160121070721", 09:29:50 INFO - "Change" 09:29:50 INFO - ], 09:29:50 INFO - [ 09:29:50 INFO - "builduid", 09:29:50 INFO - "4acd06258fd44107ba4fa9f0cb53b611", 09:29:50 INFO - "Change" 09:29:50 INFO - ], 09:29:50 INFO - [ 09:29:50 INFO - "pgo_build", 09:29:50 INFO - "True", 09:29:50 INFO - "Change" 09:29:50 INFO - ] 09:29:50 INFO - ], 09:29:50 INFO - "revision": "c018fbb65ecb505bc9e52fa617940ba8da5501b3" 09:29:50 INFO - } 09:29:50 INFO - ], 09:29:50 INFO - "revision": "c018fbb65ecb505bc9e52fa617940ba8da5501b3" 09:29:50 INFO - } 09:29:50 INFO - } 09:29:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 09:29:50 INFO - Running post-action listener: _resource_record_post_action 09:29:50 INFO - ##### 09:29:50 INFO - ##### Running download-and-extract step. 09:29:50 INFO - ##### 09:29:50 INFO - Running pre-action listener: _resource_record_pre_action 09:29:50 INFO - Running main action method: download_and_extract 09:29:50 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:29: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')]} 09:29:50 INFO - https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:29:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/test_packages.json 09:29:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/test_packages.json 09:29:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:29:51 INFO - Downloaded 1302 bytes. 09:29:51 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:29:51 INFO - Using the following test package requirements: 09:29:51 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:29:51 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:51 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:29:51 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:51 INFO - u'jsshell-linux-x86_64.zip'], 09:29:51 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:51 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:29:51 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:29:51 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:51 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:29:51 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:51 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:29:51 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:51 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:29:51 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:29:51 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:51 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:29:51 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:51 INFO - https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:29:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:29:52 INFO - Downloaded 23159148 bytes. 09:29:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:29:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:29:53 INFO - Return code: 0 09:29:53 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:53 INFO - https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:29:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:29:54 INFO - Downloaded 23159148 bytes. 09:29:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:29:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:29:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:29:55 INFO - Return code: 0 09:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:55 INFO - https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:29:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:29:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:29:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:29:56 INFO - Downloaded 11132038 bytes. 09:29:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:29:56 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:29:56 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:29:56 INFO - Return code: 0 09:29: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')]} 09:29:56 INFO - https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:29:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:29:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:29:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:29:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:29:58 INFO - Downloaded 58149182 bytes. 09:29:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:29:58 INFO - mkdir: /builds/slave/test-pgo/properties 09:29:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:29:58 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:29:58 INFO - Contents: 09:29:58 INFO - build_url:https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:29:58 INFO - Running post-action listener: _resource_record_post_action 09:29:58 INFO - Running post-action listener: set_extra_try_arguments 09:29:58 INFO - ##### 09:29:58 INFO - ##### Running populate-webroot step. 09:29:58 INFO - ##### 09:29:58 INFO - Running pre-action listener: _resource_record_pre_action 09:29:58 INFO - Running main action method: populate_webroot 09:29:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:58 INFO - '--tppagecycles', 09:29:58 INFO - '7'], 09:29:58 INFO - 'tests': ['tsvgm']}}, 09:29:58 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:29:58 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:58 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:29:58 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:29:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'tests': ['damp', 'tps']}, 09:29:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['damp', 'tps']}, 09:29:58 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:29:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:58 INFO - 'other': {'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore']}, 09:29:58 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore']}, 09:29:58 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore']}, 09:29:58 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore']}, 09:29:58 INFO - 'other_l64': {'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore']}, 09:29:58 INFO - 'other_nol64': {'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore']}, 09:29:58 INFO - 'svgr': {'tests': ['tsvgx', 09:29:58 INFO - 'tsvgr_opacity', 09:29:58 INFO - 'tart', 09:29:58 INFO - 'tscrollx', 09:29:58 INFO - 'cart']}, 09:29:58 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['tsvgx', 09:29:58 INFO - 'tsvgr_opacity', 09:29:58 INFO - 'tart', 09:29:58 INFO - 'tscrollx', 09:29:58 INFO - 'cart']}, 09:29:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'tests': ['tp5o']}, 09:29:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--e10s'], 09:29:58 INFO - 'tests': ['tp5o']}, 09:29:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--xperf_path', 09:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:58 INFO - 'tests': ['tp5n']}, 09:29:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--e10s', 09:29:58 INFO - '--xperf_path', 09:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:58 INFO - 'tests': ['tp5n']}}, 09:29:58 INFO - 'talos.zip': {'path': '', 09:29:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:58 INFO - Running post-action listener: _resource_record_post_action 09:29:58 INFO - ##### 09:29:58 INFO - ##### Running create-virtualenv step. 09:29:58 INFO - ##### 09:29:58 INFO - Running pre-action listener: _resource_record_pre_action 09:29:58 INFO - Running main action method: create_virtualenv 09:29:58 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:29:58 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 09:29:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:29:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:58 INFO - Using real prefix '/usr' 09:29:58 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:29:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:00 INFO - Installing pip.................done. 09:30:00 INFO - Return code: 0 09:30:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:00 INFO - Using env: {'DISPLAY': ':0', 09:30:00 INFO - 'HOME': '/home/cltbld', 09:30:00 INFO - 'LANG': 'en_US.UTF-8', 09:30:00 INFO - 'LANGUAGE': 'en_US:en', 09:30:00 INFO - 'LOGNAME': 'cltbld', 09:30:00 INFO - 'MAIL': '/var/mail/cltbld', 09:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:00 INFO - 'MOZ_NO_REMOTE': '1', 09:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:00 INFO - 'NO_EM_RESTART': '1', 09:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:00 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:00 INFO - 'SHELL': '/bin/bash', 09:30:00 INFO - 'SHLVL': '1', 09:30:00 INFO - 'TERM': 'linux', 09:30:00 INFO - 'TMOUT': '86400', 09:30:00 INFO - 'USER': 'cltbld', 09:30:00 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:00 INFO - '_': '/tools/buildbot/bin/python'} 09:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:00 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 09:30:00 INFO - Cleaning up... 09:30:00 INFO - Return code: 0 09:30:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:00 INFO - Using env: {'DISPLAY': ':0', 09:30:00 INFO - 'HOME': '/home/cltbld', 09:30:00 INFO - 'LANG': 'en_US.UTF-8', 09:30:00 INFO - 'LANGUAGE': 'en_US:en', 09:30:00 INFO - 'LOGNAME': 'cltbld', 09:30:00 INFO - 'MAIL': '/var/mail/cltbld', 09:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:00 INFO - 'MOZ_NO_REMOTE': '1', 09:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:00 INFO - 'NO_EM_RESTART': '1', 09:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:00 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:00 INFO - 'SHELL': '/bin/bash', 09:30:00 INFO - 'SHLVL': '1', 09:30:00 INFO - 'TERM': 'linux', 09:30:00 INFO - 'TMOUT': '86400', 09:30:00 INFO - 'USER': 'cltbld', 09:30:00 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:00 INFO - '_': '/tools/buildbot/bin/python'} 09:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:01 INFO - Downloading/unpacking psutil>=0.7.1 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 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 09:30:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:02 INFO - Installing collected packages: psutil 09:30:02 INFO - Running setup.py install for psutil 09:30:03 INFO - building 'psutil._psutil_linux' extension 09:30:03 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 09:30:03 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 09:30:03 INFO - building 'psutil._psutil_posix' extension 09:30:03 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 09:30:03 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 09:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:03 INFO - Successfully installed psutil 09:30:03 INFO - Cleaning up... 09:30:03 INFO - Return code: 0 09:30:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:30: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')]} 09:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30: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')]} 09:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:03 INFO - Using env: {'DISPLAY': ':0', 09:30:03 INFO - 'HOME': '/home/cltbld', 09:30:03 INFO - 'LANG': 'en_US.UTF-8', 09:30:03 INFO - 'LANGUAGE': 'en_US:en', 09:30:03 INFO - 'LOGNAME': 'cltbld', 09:30:03 INFO - 'MAIL': '/var/mail/cltbld', 09:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:03 INFO - 'MOZ_NO_REMOTE': '1', 09:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:03 INFO - 'NO_EM_RESTART': '1', 09:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:03 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:03 INFO - 'SHELL': '/bin/bash', 09:30:03 INFO - 'SHLVL': '1', 09:30:03 INFO - 'TERM': 'linux', 09:30:03 INFO - 'TMOUT': '86400', 09:30:03 INFO - 'USER': 'cltbld', 09:30:03 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:03 INFO - '_': '/tools/buildbot/bin/python'} 09:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:30:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:30:05 INFO - Installing collected packages: mozsystemmonitor 09:30:05 INFO - Running setup.py install for mozsystemmonitor 09:30:05 INFO - Successfully installed mozsystemmonitor 09:30:05 INFO - Cleaning up... 09:30:05 INFO - Return code: 0 09:30:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:30: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')]} 09:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30: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')]} 09:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:05 INFO - Using env: {'DISPLAY': ':0', 09:30:05 INFO - 'HOME': '/home/cltbld', 09:30:05 INFO - 'LANG': 'en_US.UTF-8', 09:30:05 INFO - 'LANGUAGE': 'en_US:en', 09:30:05 INFO - 'LOGNAME': 'cltbld', 09:30:05 INFO - 'MAIL': '/var/mail/cltbld', 09:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:05 INFO - 'MOZ_NO_REMOTE': '1', 09:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:05 INFO - 'NO_EM_RESTART': '1', 09:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:05 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:05 INFO - 'SHELL': '/bin/bash', 09:30:05 INFO - 'SHLVL': '1', 09:30:05 INFO - 'TERM': 'linux', 09:30:05 INFO - 'TMOUT': '86400', 09:30:05 INFO - 'USER': 'cltbld', 09:30:05 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:05 INFO - '_': '/tools/buildbot/bin/python'} 09:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:05 INFO - Downloading/unpacking blobuploader==1.2.4 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30:07 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:30:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:08 INFO - Downloading docopt-0.6.1.tar.gz 09:30:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:30:08 INFO - Installing collected packages: blobuploader, requests, docopt 09:30:08 INFO - Running setup.py install for blobuploader 09:30:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:30:08 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:30:08 INFO - Running setup.py install for requests 09:30:09 INFO - Running setup.py install for docopt 09:30:09 INFO - Successfully installed blobuploader requests docopt 09:30:09 INFO - Cleaning up... 09:30:09 INFO - Return code: 0 09:30:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:30:09 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')]} 09:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:09 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')]} 09:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 09:30:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 09:30:09 INFO - Using env: {'DISPLAY': ':0', 09:30:09 INFO - 'HOME': '/home/cltbld', 09:30:09 INFO - 'LANG': 'en_US.UTF-8', 09:30:09 INFO - 'LANGUAGE': 'en_US:en', 09:30:09 INFO - 'LOGNAME': 'cltbld', 09:30:09 INFO - 'MAIL': '/var/mail/cltbld', 09:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:09 INFO - 'MOZ_NO_REMOTE': '1', 09:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:09 INFO - 'NO_EM_RESTART': '1', 09:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:09 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:09 INFO - 'SHELL': '/bin/bash', 09:30:09 INFO - 'SHLVL': '1', 09:30:09 INFO - 'TERM': 'linux', 09:30:09 INFO - 'TMOUT': '86400', 09:30:09 INFO - 'USER': 'cltbld', 09:30:09 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:09 INFO - '_': '/tools/buildbot/bin/python'} 09:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:09 INFO - Running setup.py (path:/tmp/pip-pIAFjz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:09 INFO - Running setup.py (path:/tmp/pip-VLJG8R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:09 INFO - Running setup.py (path:/tmp/pip-xaG2rR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:09 INFO - Running setup.py (path:/tmp/pip-5q7kD_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:09 INFO - Running setup.py (path:/tmp/pip-pYx9Eg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:09 INFO - Running setup.py (path:/tmp/pip-rfhqjh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:09 INFO - Running setup.py (path:/tmp/pip-nOk861-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:10 INFO - Running setup.py (path:/tmp/pip-S7IgQJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:10 INFO - Running setup.py (path:/tmp/pip-EICBwz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:10 INFO - Running setup.py (path:/tmp/pip-v74Wka-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:30:10 INFO - Running setup.py (path:/tmp/pip-TAaVYx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:30:10 INFO - Running setup.py (path:/tmp/pip-yrb3x2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:30:10 INFO - Running setup.py (path:/tmp/pip-9EEyMn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:30:10 INFO - Running setup.py (path:/tmp/pip-YZAyws-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:30:10 INFO - Running setup.py (path:/tmp/pip-8pWtMW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:30:10 INFO - Running setup.py (path:/tmp/pip-cD3VLQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:30:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:30:10 INFO - Running setup.py (path:/tmp/pip-NH9Cd7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:30:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:10 INFO - Running setup.py install for manifestparser 09:30:10 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:30:10 INFO - Running setup.py install for mozcrash 09:30:10 INFO - Running setup.py install for mozdebug 09:30:11 INFO - Running setup.py install for mozdevice 09:30:11 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Running setup.py install for mozfile 09:30:11 INFO - Running setup.py install for mozhttpd 09:30:11 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Running setup.py install for mozinfo 09:30:11 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Running setup.py install for mozInstall 09:30:11 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Running setup.py install for mozleak 09:30:11 INFO - Running setup.py install for mozlog 09:30:11 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Running setup.py install for moznetwork 09:30:11 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:30:11 INFO - Running setup.py install for mozprocess 09:30:11 INFO - Running setup.py install for mozprofile 09:30:12 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:30:12 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:30:12 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:30:12 INFO - Running setup.py install for mozrunner 09:30:12 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:30:12 INFO - Running setup.py install for mozscreenshot 09:30:12 INFO - Running setup.py install for moztest 09:30:12 INFO - Running setup.py install for mozversion 09:30:12 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:30:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:12 INFO - Cleaning up... 09:30:12 INFO - Return code: 0 09:30:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:30:12 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')]} 09:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:12 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')]} 09:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:12 INFO - Using env: {'DISPLAY': ':0', 09:30:12 INFO - 'HOME': '/home/cltbld', 09:30:12 INFO - 'LANG': 'en_US.UTF-8', 09:30:12 INFO - 'LANGUAGE': 'en_US:en', 09:30:12 INFO - 'LOGNAME': 'cltbld', 09:30:12 INFO - 'MAIL': '/var/mail/cltbld', 09:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:12 INFO - 'MOZ_NO_REMOTE': '1', 09:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:12 INFO - 'NO_EM_RESTART': '1', 09:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:12 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:12 INFO - 'SHELL': '/bin/bash', 09:30:12 INFO - 'SHLVL': '1', 09:30:12 INFO - 'TERM': 'linux', 09:30:12 INFO - 'TMOUT': '86400', 09:30:12 INFO - 'USER': 'cltbld', 09:30:12 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:12 INFO - '_': '/tools/buildbot/bin/python'} 09:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:12 INFO - Running setup.py (path:/tmp/pip-JWIHDr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:30:12 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)) 09:30:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:12 INFO - Running setup.py (path:/tmp/pip-AhLE0P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:30:12 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)) 09:30:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:12 INFO - Running setup.py (path:/tmp/pip-jDpUCH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:30:12 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)) 09:30:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:12 INFO - Running setup.py (path:/tmp/pip-znFzPz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:13 INFO - Running setup.py (path:/tmp/pip-dhW3lK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:13 INFO - Running setup.py (path:/tmp/pip-zj5uye-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:13 INFO - Running setup.py (path:/tmp/pip-ARJ811-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:13 INFO - Running setup.py (path:/tmp/pip-oSaXoZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:13 INFO - Running setup.py (path:/tmp/pip-LXf_tH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:13 INFO - Running setup.py (path:/tmp/pip-7Wnq5m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:30:13 INFO - Running setup.py (path:/tmp/pip-4Di434-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:30:13 INFO - Running setup.py (path:/tmp/pip-JsG2an-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:30:13 INFO - Running setup.py (path:/tmp/pip-bGxQ4W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:30:13 INFO - Running setup.py (path:/tmp/pip-FnAL79-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:30:13 INFO - Running setup.py (path:/tmp/pip-aSsWBj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:30:13 INFO - Running setup.py (path:/tmp/pip-oGuhQN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:30:13 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)) 09:30:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:30:13 INFO - Running setup.py (path:/tmp/pip-_M3YjB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:30:13 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)) 09:30:13 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)) 09:30:13 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)) 09:30:13 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)) 09:30:13 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:13 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:13 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 09:30:13 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 09:30:13 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 09:30:13 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 09:30:13 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 09:30:13 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 09:30:15 INFO - Downloading blessings-1.6.tar.gz 09:30:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:30:15 INFO - Installing collected packages: blessings 09:30:15 INFO - Running setup.py install for blessings 09:30:15 INFO - Successfully installed blessings 09:30:15 INFO - Cleaning up... 09:30:15 INFO - Return code: 0 09:30:15 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:30:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:30:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:30:16 INFO - Reading from file tmpfile_stdout 09:30:16 INFO - Current package versions: 09:30:16 INFO - argparse == 1.2.1 09:30:16 INFO - blessings == 1.6 09:30:16 INFO - blobuploader == 1.2.4 09:30:16 INFO - docopt == 0.6.1 09:30:16 INFO - manifestparser == 1.1 09:30:16 INFO - mozInstall == 1.12 09:30:16 INFO - mozcrash == 0.16 09:30:16 INFO - mozdebug == 0.1 09:30:16 INFO - mozdevice == 0.47 09:30:16 INFO - mozfile == 1.2 09:30:16 INFO - mozhttpd == 0.7 09:30:16 INFO - mozinfo == 0.9 09:30:16 INFO - mozleak == 0.1 09:30:16 INFO - mozlog == 3.1 09:30:16 INFO - moznetwork == 0.27 09:30:16 INFO - mozprocess == 0.22 09:30:16 INFO - mozprofile == 0.27 09:30:16 INFO - mozrunner == 6.11 09:30:16 INFO - mozscreenshot == 0.1 09:30:16 INFO - mozsystemmonitor == 0.0 09:30:16 INFO - moztest == 0.7 09:30:16 INFO - mozversion == 1.4 09:30:16 INFO - psutil == 3.1.1 09:30:16 INFO - requests == 1.2.3 09:30:16 INFO - wsgiref == 0.1.2 09:30:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:16 INFO - Using env: {'DISPLAY': ':0', 09:30:16 INFO - 'HOME': '/home/cltbld', 09:30:16 INFO - 'LANG': 'en_US.UTF-8', 09:30:16 INFO - 'LANGUAGE': 'en_US:en', 09:30:16 INFO - 'LOGNAME': 'cltbld', 09:30:16 INFO - 'MAIL': '/var/mail/cltbld', 09:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:16 INFO - 'MOZ_NO_REMOTE': '1', 09:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:16 INFO - 'NO_EM_RESTART': '1', 09:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:16 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:16 INFO - 'SHELL': '/bin/bash', 09:30:16 INFO - 'SHLVL': '1', 09:30:16 INFO - 'TERM': 'linux', 09:30:16 INFO - 'TMOUT': '86400', 09:30:16 INFO - 'USER': 'cltbld', 09:30:16 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:16 INFO - '_': '/tools/buildbot/bin/python'} 09:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 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)) 09:30:16 INFO - Cleaning up... 09:30:16 INFO - Return code: 0 09:30:16 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:16 INFO - Using env: {'DISPLAY': ':0', 09:30:16 INFO - 'HOME': '/home/cltbld', 09:30:16 INFO - 'LANG': 'en_US.UTF-8', 09:30:16 INFO - 'LANGUAGE': 'en_US:en', 09:30:16 INFO - 'LOGNAME': 'cltbld', 09:30:16 INFO - 'MAIL': '/var/mail/cltbld', 09:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:16 INFO - 'MOZ_NO_REMOTE': '1', 09:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:16 INFO - 'NO_EM_RESTART': '1', 09:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:16 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:16 INFO - 'SHELL': '/bin/bash', 09:30:16 INFO - 'SHLVL': '1', 09:30:16 INFO - 'TERM': 'linux', 09:30:16 INFO - 'TMOUT': '86400', 09:30:16 INFO - 'USER': 'cltbld', 09:30:16 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:16 INFO - '_': '/tools/buildbot/bin/python'} 09:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:16 INFO - Downloading/unpacking jsonschema 09:30:16 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 09:30:16 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 09:30:16 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 09:30:16 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 09:30:16 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 09:30:16 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 09:30:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:30:18 INFO - Downloading/unpacking functools32 (from jsonschema) 09:30:18 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 09:30:18 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 09:30:18 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 09:30:18 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 09:30:18 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 09:30:18 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 09:30:18 INFO - Downloading functools32-3.2.3-2.tar.gz 09:30:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:30:18 INFO - warning: no files found matching '*.txt' 09:30:18 INFO - no previously-included directories found matching 'build' 09:30:18 INFO - no previously-included directories found matching 'dist' 09:30:18 INFO - no previously-included directories found matching '.git*' 09:30:18 INFO - Installing collected packages: jsonschema, functools32 09:30:18 INFO - Running setup.py install for functools32 09:30:18 INFO - warning: no files found matching '*.txt' 09:30:18 INFO - no previously-included directories found matching 'build' 09:30:18 INFO - no previously-included directories found matching 'dist' 09:30:18 INFO - no previously-included directories found matching '.git*' 09:30:18 INFO - Successfully installed jsonschema functools32 09:30:18 INFO - Cleaning up... 09:30:18 INFO - Return code: 0 09:30:18 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:30:18 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')]} 09:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:18 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')]} 09:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f569f520f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bfd600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', '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 09:30:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 09:30:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 09:30:18 INFO - Using env: {'DISPLAY': ':0', 09:30:18 INFO - 'HOME': '/home/cltbld', 09:30:18 INFO - 'LANG': 'en_US.UTF-8', 09:30:18 INFO - 'LANGUAGE': 'en_US:en', 09:30:18 INFO - 'LOGNAME': 'cltbld', 09:30:18 INFO - 'MAIL': '/var/mail/cltbld', 09:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:18 INFO - 'MOZ_NO_REMOTE': '1', 09:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:18 INFO - 'NO_EM_RESTART': '1', 09:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:18 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:18 INFO - 'SHELL': '/bin/bash', 09:30:18 INFO - 'SHLVL': '1', 09:30:18 INFO - 'TERM': 'linux', 09:30:18 INFO - 'TMOUT': '86400', 09:30:18 INFO - 'USER': 'cltbld', 09:30:18 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:18 INFO - '_': '/tools/buildbot/bin/python'} 09:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:19 INFO - Downloading/unpacking flake8 09:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:30:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:30:20 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 09:30:20 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 09:30:20 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 09:30:20 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 09:30:20 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 09:30:20 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 09:30:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:30:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:30:21 INFO - no previously-included directories found matching 'docs/_build' 09:30:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:30:21 INFO - Running setup.py install for pep8 09:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:30:21 INFO - no previously-included directories found matching 'docs/_build' 09:30:21 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:30:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:30:21 INFO - Cleaning up... 09:30:21 INFO - Return code: 0 09:30:21 INFO - Running post-action listener: _resource_record_post_action 09:30:21 INFO - Running post-action listener: _start_resource_monitoring 09:30:21 INFO - Starting resource monitoring. 09:30:21 INFO - ##### 09:30:21 INFO - ##### Running install step. 09:30:21 INFO - ##### 09:30:21 INFO - Running pre-action listener: _resource_record_pre_action 09:30:21 INFO - Running main action method: install 09:30:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:30:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:30:21 INFO - Reading from file tmpfile_stdout 09:30:21 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:30:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:30:22 INFO - Reading from file tmpfile_stdout 09:30:22 INFO - Output received: 09:30:22 INFO - Usage: mozinstall [options] installer 09:30:22 INFO - Options: 09:30:22 INFO - -h, --help show this help message and exit 09:30:22 INFO - -d DEST, --destination=DEST 09:30:22 INFO - Directory to install application into. [default: 09:30:22 INFO - "/builds/slave/test-pgo"] 09:30:22 INFO - --app=APP Application being installed. [default: firefox] 09:30:22 INFO - mkdir: /builds/slave/test-pgo/build/application 09:30:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:30:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:30:36 INFO - Reading from file tmpfile_stdout 09:30:36 INFO - Output received: 09:30:36 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:30:36 INFO - Running post-action listener: _resource_record_post_action 09:30:36 INFO - ##### 09:30:36 INFO - ##### Running run-tests step. 09:30:36 INFO - ##### 09:30:36 INFO - Running pre-action listener: _resource_record_pre_action 09:30:36 INFO - Running main action method: run_tests 09:30:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:30:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:30:36 INFO - Python 2.7.3 09:30:36 INFO - Return code: 0 09:30:36 INFO - grabbing minidump binary from tooltool 09:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:36 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 0x2c8c350>, '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 0x2c8d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8d6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:30:36 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 09:30:36 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 09:30:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:36 INFO - Return code: 0 09:30:36 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:30:36 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:30:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:30:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:30:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:30:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:30:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:30:37 INFO - Return code: 0 09:30:37 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-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:30:37 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-Aurora --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 09:30:37 INFO - Using env: {'DISPLAY': ':0', 09:30:37 INFO - 'HOME': '/home/cltbld', 09:30:37 INFO - 'LANG': 'en_US.UTF-8', 09:30:37 INFO - 'LANGUAGE': 'en_US:en', 09:30:37 INFO - 'LOGNAME': 'cltbld', 09:30:37 INFO - 'MAIL': '/var/mail/cltbld', 09:30:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:30:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:37 INFO - 'MOZ_NO_REMOTE': '1', 09:30:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:30:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:37 INFO - 'NO_EM_RESTART': '1', 09:30:37 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:30:37 INFO - 'PWD': '/builds/slave/test-pgo', 09:30:37 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:30:37 INFO - 'SHELL': '/bin/bash', 09:30:37 INFO - 'SHLVL': '1', 09:30:37 INFO - 'TERM': 'linux', 09:30:37 INFO - 'TMOUT': '86400', 09:30:37 INFO - 'USER': 'cltbld', 09:30:37 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727', 09:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:37 INFO - '_': '/tools/buildbot/bin/python'} 09:30:37 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 09:30:37 INFO - mozversion INFO | application_buildid: 20160121070721 09:30:37 INFO - mozversion INFO | application_changeset: c018fbb65ecb505bc9e52fa617940ba8da5501b3 09:30:37 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 09:30:37 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:37 INFO - mozversion INFO | application_name: Firefox 09:30:37 INFO - mozversion INFO | application_remotingname: firefox-dev 09:30:37 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:30:37 INFO - mozversion INFO | application_vendor: Mozilla 09:30:37 INFO - mozversion INFO | application_version: 45.0a2 09:30:37 INFO - mozversion INFO | platform_buildid: 20160121070721 09:30:37 INFO - mozversion INFO | platform_changeset: c018fbb65ecb505bc9e52fa617940ba8da5501b3 09:30:37 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:30:37 INFO - mozversion INFO | platform_version: 45.0a2 09:30:37 INFO - 2016-01-21 09:30:37,949 DEBUG : using testdate: 1453397437 09:30:37 INFO - 2016-01-21 09:30:37,949 DEBUG : actual date: 1453397437 09:30:37 INFO - 2016-01-21 09:30:37,957 INFO : starting webserver on 'localhost:57275' 09:30:37 INFO - 2016-01-21 09:30:37,958 INFO : Starting test suite talos-linux64-ix-046 09:30:37 INFO - 2016-01-21 09:30:37,958 INFO : Starting test dromaeo_css 09:30:37 INFO - 2016-01-21 09:30:37,958 DEBUG : operating with platform_type : linux_ 09:30:37 INFO - 2016-01-21 09:30:37,959 INFO : Initialising browser for dromaeo_css test... 09:30:37 INFO - 2016-01-21 09:30:37,968 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpubjrc9/profile http://localhost:57275/getInfo.html 09:30:41 INFO - 2016-01-21 09:30:41,879 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:30:41 INFO - 2016-01-21 09:30:41,879 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:30:41 INFO - 2016-01-21 09:30:41,879 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:30:41 INFO - 2016-01-21 09:30:41,879 DEBUG : BROWSER_OUTPUT: __metrics 09:30:42 INFO - 2016-01-21 09:30:42,064 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] 09:30:43 INFO - 2016-01-21 09:30:43,005 INFO : Browser initialized. 09:30:43 INFO - 2016-01-21 09:30:43,005 INFO : Running cycle 1/1 for dromaeo_css test... 09:30:43 INFO - 2016-01-21 09:30:43,006 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpubjrc9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:30:54 INFO - 2016-01-21 09:30:54,863 DEBUG : BROWSER_OUTPUT: RSS: Main: 137052160 09:30:54 INFO - 2016-01-21 09:30:54,863 DEBUG : BROWSER_OUTPUT: 09:31:45 INFO - 2016-01-21 09:31:45,615 DEBUG : BROWSER_OUTPUT: 1453397505613 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] 09:33:53 INFO - 2016-01-21 09:33:53,995 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57275/tests/dromaeo/cssquery-ext.html) 09:33:54 INFO - 2016-01-21 09:33:54,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 231829504 09:33:54 INFO - 2016-01-21 09:33:54,984 DEBUG : BROWSER_OUTPUT: 09:36:57 INFO - 2016-01-21 09:36:57,183 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/dromaeo/cssquery-ext.html (next: http://localhost:57275/tests/dromaeo/cssquery-jquery.html) 09:36:57 INFO - 2016-01-21 09:36:57,749 DEBUG : BROWSER_OUTPUT: RSS: Main: 216506368 09:36:57 INFO - 2016-01-21 09:36:57,749 DEBUG : BROWSER_OUTPUT: 09:39:59 INFO - 2016-01-21 09:39:59,625 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57275/tests/dromaeo/cssquery-mootools.html) 09:40:00 INFO - 2016-01-21 09:40:00,200 DEBUG : BROWSER_OUTPUT: RSS: Main: 207433728 09:40:00 INFO - 2016-01-21 09:40:00,200 DEBUG : BROWSER_OUTPUT: 09:43:01 INFO - 2016-01-21 09:43:01,916 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57275/tests/dromaeo/cssquery-prototype.html) 09:43:02 INFO - 2016-01-21 09:43:02,478 DEBUG : BROWSER_OUTPUT: RSS: Main: 198389760 09:43:02 INFO - 2016-01-21 09:43:02,478 DEBUG : BROWSER_OUTPUT: 09:46:04 INFO - 2016-01-21 09:46:04,804 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57275/tests/dromaeo/cssquery-yui.html) 09:46:05 INFO - 2016-01-21 09:46:05,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 201601024 09:46:05 INFO - 2016-01-21 09:46:05,378 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,691 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/dromaeo/cssquery-yui.html (next: http://localhost:57275/tests/dromaeo/cssquery-dojo.html) 09:49:07 INFO - 2016-01-21 09:49:07,937 DEBUG : BROWSER_OUTPUT: RSS: Main: 245182464 09:49:07 INFO - 2016-01-21 09:49:07,937 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,937 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:49:07 INFO - 2016-01-21 09:49:07,939 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:49:07 INFO - 2016-01-21 09:49:07,939 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:49:07 INFO - 2016-01-21 09:49:07,939 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:49:07 INFO - 2016-01-21 09:49:07,940 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3259.5762550868385;3331.516930045145;3365.663433656633;3370.949435758464;3384.847681854317;5416.918746218805;5431.619786614933;5433.4566543345645;5478.369987451444;5494.230807686917;5456.972715136429;5499.835004949848;5503.7798488060425;5507.173923911415;5524.088525393306;4894.657373983822;4973.676711013782;4981.227909674007;5041.1682072458;5066.797328106872;4925.852224433273;4982.476839931809;4985.202367621163;4987.551120399164;4987.950120498803;4853.39332583427;4913.680610760307;4976.751162441881;4984.75076246188;4994.400671919375;4960.950390496085;5058.747062646853;5059.747012649353;5064.366954130752;5086.898262034775;4789.592884604778;4950.9504904950845;4960.156773348539;4970.179920313144;4998.400191976986;5207.297014902978;5219.791208351661;5306;5306.336707911511;5320.920186197211;5875.441833025856;5914.763409463616;5916.290045194562;5922.3485416604135;5931.377205393414;5773.509251713609;5889.970550147222;5957.91063134049;5959.940400596025;5962.165296858443;5450.073487507133;5541.196526503634;5543.473370029841;5552.972235138839;5582.385937546866;6046.727897244635;6073.331933487313;6075.2405949256345;6114;6117.143599896018;5511.448855114457;5663.093904975184;5695.772169113272;5854.180414741854;5881.823545293648;5885.2349194605285;5918.674472904032;5927.555433342516;5929.14027466015;5931.673757943268;5839.007368747323;5866.266716660418;5880.705964701748;5883.441073098049;5887.499562537232;5663.065594176915;5754.597178197486;5775.971120144456;5881.617694849821;5899.911501327483;4539.886502837456;4560.5895469407915;4581.816727330869;4584.312353147055;4613.63090952723;4108.938365924513;4119.382092686121;4124.05146816234;4126.484189476316;4152.667786577066;5475.616706830563;5489.752961116759;5568.387477377485;5601.5238704709745;5703.144528320785;5915.704214789243;5939.495142912814;5941.37615550361;5943.138244954457;5944.762209511571;5580.86047848807;5633.211350410946;5636.774529018793;5638.069718496484;5665.065264231355;4942.654014218971;5077.669951453144;5127.154019586801;5199.350081239845;5223.190405487156;6064.272287325461;6072.757089716361;6089.147519347295;6093.994490909039;6106.603070800384;5756.136579513107;5937.109433584824;5944.791932282349;5950.16697662319;5950.553708471795;5810.360860305447;5890.4993075589055;5915.763369465173;5917.792877249276;5931.881362372815;5728.923617661295;5775;5778.133280008033;5783;5807.94192058074;5708;5819.127130930395;5883.764649413975;5892.6759028253855;5903.498202652822;5740.942590574041;5795.681237531976;5799.971000144972;5800.1589769484035;5834.18321435008;5796.884062318814;5835.066389377679;5843.181954526454;5845.152452894392;5848.707564621837;5528.170774383874;5656.0667489865355;5677.630953987978;5697.6296540724725;5740.454656807597;5763.452472015152;5779.624324418899;5780.653160810365;5788;5793.102069179284;5624.465675760796;5680.6591604503865;5685.630434021776;5686.09022556389;5703.258576385209 09:49:07 INFO - 2016-01-21 09:49:07,942 DEBUG : BROWSER_OUTPUT: |1;ext.html;578.8089930322993;614.9262088549375;632.099258556557;633.7306644676012;687.2680595166148;31348.84325578372;32584.185395365126;40885.59114408855;41121.383179252334;41151.79424102883;45270;45570.088598228016;45652.315215271796;45806.7709661452;45827.54172458274;8097.473664211837;9076.273898088137;9206.76983075425;9235.676751313706;9237.769055773591;8124.796880077986;10836.783264334708;11021.724456888562;11034.062104721108;11039;10773.192010599236;10895.727606809814;10897.509612067437;10906.5092070857;10907.672769816918;8300.916990830076;8434.662613495453;10696.304740191863;10789.352638841694;10818.67543973678;10618.469076546218;10767.784644307148;10781.191410644233;10785.460726963698;10786.191035672357;41473.377899331535;41998.580014199775;42002;42004.36993445101;42018.579814201774;3559.7864128152396;3576.409892367782;3595.26297109092;3644.0707619556842;3777.131259810259;31739.206519836956;33609.83195084034;33678.6632133678;33704.15739606505;33751.662483375345;3292.3579901919134;3510.894673159784;3609.3322735294055;3611.1694310308512;3628.38317486028;301104;301212;302790;302889;305042;65821.67089164523;68060;68884;69022.65488672524;69259.3074069263;62077.68961155256;81581;81904;82118;82267.58866205631;18099.819001810076;18441.5389615258;18456.169472373775;18459;18461.815381846278;14125.85874141266;14306;14332.856671433361;14341.85658143426;14343.211123388106;22516.32451026472;22989.540209195722;23010;23041.53916921652;23119.07523699067;8807.207351338411;8940.50827204497;8956.910430895608;9028.310629689706;9044.366894317334;1126.357975953715;1146.9139814513778;1174.2074099982478;1238.5417395563518;1242.6644805902356;28899.277518061797;29124.854375727984;29193.56209656857;29213.974015915734;29460;1116.2356121901305;1134.22872446737;1144.5250221158126;1203.957861474844;1314.4610709609015;14192.848865258482;14599.562013139623;14907.105573665614;17364.565885852953;17430.82569174292;419614;425600;426523;426669;427242;57582;58044.70977645085;58099.709501453064;58161.709191453774;58169.2549223524;1408.126961284031;1410.4005797536006;1417.2701058954647;1448.167303800298;1451.8693317601467;30719.692803072576;30861.22846928755;30926.53610195894;31522.211944700648;31547.842260788548;51894.481055190474;52498.73750631375;52521.47478525166;52557;52559.21161182662;323607;328175;421675;426345;467131;45393.31910021418;45662.40181593628;45708.314375283735;46231.76884115558;46424.53575464202;48386.27420588765;48587.27119093287;48820.75589622029;49203.778004833795;49842;39765.403518947816;40594;40989.38515922323;41020.589794101674;41381;37744.811275943444;42837;43439.34840977451;43944.34083488814;44828.32757508705;38468.807655961544;38721.80639096787;43877.78061109674;44792.328115078955;44890.326645099696;32996.50505242471;33958;33997;34300.14249643679;34922.82538587291;26318;26732.732672673024;26765.86617066902;26819.463610727285;27123.72876271212 09:49:07 INFO - 2016-01-21 09:49:07,943 DEBUG : BROWSER_OUTPUT: |2;jquery.html;376.0579747731931;377.62615011138973;381.759074234245;383.7045474984262;384.1031192166292;16842.747358789613;19746.210151593918;19959.501012474695;20368.185272589097;20406.285779997703;17984;18058.548536286566;18227.635447291046;18422.44732658019;18422.447326580223;5567.66594004361;5700.230468886689;5700.714964251792;5706.486416222539;5710.543156547466;5400.459954004607;5552.444755524456;5566.972165139168;5609.775608975636;5612;3989.0825110224664;4097.323941549641;5526.530244929186;5533.197686335479;5535.308086489189;5632.211490391349;5772.48047675707;5806.245188125537;5810.970945145289;5811.82564523065;5572.3870374258795;5577.163425486169;5588;5716.171155182476;5724.141378793214;12738.426770795337;16991.235394407282;17184.914075429668;20101.79898201014;20629.4842628934;2101.674240492726;2293.5183611441616;2312.387217387394;2327.7267763377704;2436.5248776488593;9965.65120220796;10107.545160467796;10299.27905046647;10390.792184156275;10510.421926794023;1883.5479484923658;1947.6104779044106;1990.3033938121687;1993.2923812046804;2035.6335859545277;66578.66710666464;68837;69934.65032674855;70454;70859.29140708578;10999.34003959765;11176.161787865942;11176.441177941071;11187.160962927814;11278.041366483776;14882.255887205813;14995.550133496012;15005.549833505012;15026;15034.398624054915;8596.183362580545;8658.307335413152;8660.78348041304;8683.435576687496;8697.391182617155;8022.919770802334;8022.959885200653;8105.230003149692;8184.508929464251;8193.79515512117;4609.400777898852;4626.37543931565;4659.370984917061;4750.311204875274;4871.488493708181;1859.386402487176;1870.560418301698;1937.5059359863144;1939.0983192815409;1957.6574099532809;402.25984189092213;443.76480465353416;456.77095772629355;467.77780554236466;1012.336919317848;10591.205659575562;11679.708007299885;11933.462994165284;11970.22193557416;11979.34113623759;478.6147151543041;490.2621554560364;495.47232197709326;501.50601657367463;511.91984911835993;5554.0835762099605;6275.6862156892885;6292.014515457365;6374.681265936684;6381.968090159519;610415;621959;627263;634594;642633;89427.55286223657;91796;91805.54097229471;91925;92023;523.4739087217322;526.1345087331318;530.8964751873425;532.1326238231658;532.4675324675325;10929;11110.722231944266;11329;11520.539178433104;15277.236138193268;34362.82818585891;36201.275974480886;36607.45088823723;37418.06454838651;40229.79885100556;43850;44099.338509921734;46151;46479.30281045855;49603;10084;10187.13409360183;10263;10304.793904121725;10309.74225644365;11845.703857403634;13692;18052.63894722072;18118.09409529492;18491.260349585868;14980.775288370902;15159.924200378928;15591.76612350793;15698.215089245721;15732;15016.924915375354;15081.849181508045;15205.923970380078;15562.221888905737;15661.295241713922;16629;16737;16784.832151678816;16846;17089.914550427664;9229.307801915018;9263.120003599648;9538.14156725898;10345.017223363768;10471.842922356018;10472.394994239317;10516.106130978647;10661.253712240068;16339.673206536041;16503.752443713114 09:49:07 INFO - 2016-01-21 09:49:07,944 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1327.356232227369;1514.7046325966437;1556.9065856048642;1725.7929048514172;1842.889426634403;6877.243503214642;7453.66458509367;7657.9617101914555;7693.692252309908;7694.345980591643;7289.817754556138;7416.406687465003;7416.925830741691;7417.406607471403;7419.962900185505;2247.2808701215617;2267.1724820440586;2284.931452056441;2287.0965968442456;2288.485090854561;2310.514791893696;2339.415146213447;2361.3624321433203;2364.6453032045247;2365.3968238099346;2267.7051983242154;2286.9199578014773;2299.367673889685;2301.6777651128855;2315.3748487908256;2254.211026140854;2287.3709729824263;2317.6639387288833;2317.88410579472;2319.7680231976838;2289.8168146548237;2314.00497785952;2314.305708287524;2316.7567405422355;2318.211807985276;7672.884906726348;7737.9226207737765;7737.9226207737765;7744.070711514598;7746.845063098763;4660.603848672867;4727.95272047282;4741.051789642051;4777.235642297216;4779.354787103731;3062.433449811792;3084.228942764309;3149.2599239179003;3163.7943533670236;3192.4413227685063;983.1004630762843;1002.3334482569082;1003.2726273451689;1006.0241565681278;1009.5457044330008;10345.534450949724;10395.532201050972;10424.009719076676;10429.061384475357;10435.113015393701;4311.956880431218;4312.892177695583;4342.69601127918;4375.518692943837;4390.846320378771;8992.8651070234;9012.684556040507;9021.548922553846;9030;9039.050899655573;4825.4933232010835;4866.29438731389;4882.853514394574;4886.120498310267;4891.6575839691595;3849.480320156799;3918.1967696622123;4289.871303860889;4432.977835110803;4456.64346852251;3744.194998075427;3763.6612704856693;3779.22525882199;3779.281936432069;3804.3722785740597;1232.648695121886;1238.2632333761399;1239.3803098450774;1242.726600147984;1247.2641141726424;1246.4328730427633;1246.8067449545335;1249.8312727781815;1254.0155977557574;1282.4485471247453;6927.7228910844005;6941.444684425335;6967.756128535477;7064.858702825914;7086.149662040588;479.86323897689687;480.45468393373073;500.964932454732;504.7299694663411;505.13621706881645;5212.739363031833;5258.710770907637;5265.420803711589;5266.052110620049;5277.182036784305;8910.242629376446;8937;9198;9210.539473026322;9219.308551858638;9114.86327705085;9132.543372831331;9147.2682185425;9152.130547597968;9162.083791620784;7125.5368401053765;7240.674169662378;7252.5648461092505;7290.927090729131;7307.451941104332;6950.16598008222;6963.651817409211;7003.229644739176;7029.121359830021;7040.3663670269925;7972.202779721982;8293.792655183668;8299.253067223979;8310.83378332442;8311.584420779058;8318.875216871631;8355.874661879956;8593.269572086443;8593.52735599523;8604.78488037779;5984.790532331347;6147.0779383092895;6670.39966403026;6908.412784913338;6985.3713165815325;6152.692365381624;6170.228721409824;6186.1648677427975;6211.875762484635;6214.658193799384;6907.240203577703;7056.400205982549;7075.752348667772;7168.498205125591;7200.675969581486;6912.585244885323;6949.791506254821;7004.299570042955;7008.544444611084;7071.010058591904;6622.139121914121;6772.729090836311;6843.144606924135;6897.241303456515;6905.516613836983;6204.81385558434;6246.531510136667;6263.906041409291;6264.060390941395;6270.4670103039925;6309.242890853127;6362.395572420613;6385.7126429309865;6387.8722425550295;6389.7763578274535 09:49:07 INFO - 2016-01-21 09:49:07,944 DEBUG : BROWSER_OUTPUT: |4;prototype.html;352.5733862026948;352.75307284900566;354.1588726773912;364.12609736632095;364.3987420755751;5952.642841429517;6031;6103.694815259236;6142.877142457148;6160.106784516242;5873.324567674718;5945.37573554777;6035.939640603593;6057.454829065383;6061.909071363933;4657.091867085919;4672.836450724225;4682.016776476944;4688.9765551172195;4690.343351930733;4475.351074094255;4583.060472603109;4669.066186762644;4669.439667239936;4679.8362057328;4537.7277363358135;4639.350490931272;4647.070585882837;4649.558291962258;4659.976700116495;4629.282461218517;4632.467266264389;4643.489216186217;4644.512326205733;4656.930146047812;4092.202020605983;4093.0585965228165;4098.754074755494;4099.344104943195;4106.425100485934;5628.183913332585;5742.971285143548;5743.597948143632;5752.252207213078;5754.510866576346;5440.510354068113;5441.129419292934;5443.1563107717975;5448.809291674812;5453.754581043823;6437.678116094224;6442.774502892376;6444;6444.581102228341;6449.419552240321;4551.317302404633;4566.041131362418;4566.10960862632;4568.063546972862;4575.2450845610765;6567;6699.430548403391;6699.698513566853;6700.93299067008;6706.597604143767;4679.953200467951;4680.906381872343;4684.4378674559275;4685.344051832746;4688.226442636995;4744;4745.1221524018165;4745.335653008581;4746.881327966759;4749.168895443284;5242.423333433319;5417.674939503642;5425.755840987165;5425.7829686812875;5439.48324909133;6485.54601177913;6506.4469520091325;6517.9348206517325;6527.738890444329;6528.379803918715;1618.741001439764;1634.1747417554059;1634.689409012284;1635.9938637737705;1639.0984958272902;1051.8527406163143;1055.8627378440908;1070.03162138265;1073.1790180511914;1086.869575650927;6247.031710084944;6261.373862613702;6262.436380725757;6266.624002559861;6269.843253918689;6566.967165164144;6601.405873471411;6601.669916504252;6611.33886611335;6619.635920024445;6256.562040657202;6322.683865806784;6337.841553961188;6364.299927008025;6431.967840160863;3605.080704420356;3634.436662317345;3637.199816040467;3643.3441980443777;3645.453182022718;6763.154605674291;6891.552049116892;6908.792736217922;6917;6921.688524016431;6898;6908.723651054002;6911.067005954233;6914.170299564084;6919.273476285019;6917.688704008332;6917.965410172917;6921.515493915478;6922.134733158356;6925.134358205224;6622.304658010839;6674.399304062657;6748.460123190232;6750.2237242715755;6758.155230596176;6631.834204144935;6634.535582509178;6638.933610663831;6639.402453779183;6640.568363056386;6907;6919.20429150634;6924.723011079501;6924.753544361862;6934.791956241321;4568.383268258743;4660.39414876064;4660.4174478190225;4667.346571480063;4669.7431641260055;6717.496187785838;6727.865442691216;6730.293319201609;6738.157730283715;6739.696713647995;6565.704543295658;6565.80302590923;6566.737330506726;6566.770163044462;6568.507361947969;6440.8389790255615;6550.672466376567;6559.4752419807255;6562.704678289584;6565.277819439953;6439.3238709934785;6450.516211284079;6455.709493072729;6458.870822583616;6459.709313080829;6561.081448597296;6569.047488114292;6571.967140164268;6573.769918052845;6579.342065793382;6298.433141017331;6415.550911436155;6423.582467139621;6427.6786160690845;6431 09:49:07 INFO - 2016-01-21 09:49:07,945 DEBUG : BROWSER_OUTPUT: |5;yui.html;804.0873608454402;850.7490290364342;919.8574220995744;921.3642586615239;951.9813799234819;13982.021258511908;16037.198140093007;16378.7543186852;16385.262663180154;16450.424235151786;15363.3086511107;15421.92289038556;15435.45975890844;15463.458778942739;15470.61323466914;730.9122905251377;740.2264633458022;872.4067634008903;876.7851876290301;881.5107615273521;892.7589550821274;893.4014210478962;900.4507250577143;900.9819803603924;901.269971323227;934.8317302885478;942.5004747483845;942.853857652065;943.4197968249512;943.6838659049226;771.6990373754212;783.4515838912783;914.7987442762615;927.0775578299567;928.0487500312234;693.715576613591;694.1150033707064;694.3577191098213;694.3577191098265;694.4097517110466;13911.582652520441;14003.369848356746;14011.579652610439;14017;14033.438662453489;12057.336846473441;12282.877171228263;13056.804147937788;13281.734365312735;13541.322933853267;10760.838587421194;10775;10856.45717714119;10860.45697715119;11716.179867409284;3883.165119499321;3896.8636097736726;3970.086880017583;3976.5029371328583;3991.7006224533275;50381.24428133581;50658;51301;51438.99999999963;52848.20727689088;10444.373337599769;10626.681199564026;10764.730881727863;10814.080803131821;10829;18512.537186570175;18518;18554.907225464056;18576.535586610444;18596.907015464836;7556.1310449297935;7579.204183560757;7733.806654833562;7824.33493153077;7895.407844411578;4620.3762492063815;4742.288656701522;4818.638602104857;4871.878203044881;4899.1426500362295;3272.8363581820813;3312.354090952241;3317.950230746541;3318.7179089777155;3319.8340082996237;576.8788554403623;597.602594274811;602.7408214467821;605.3159929279885;605.8697380063307;679.1341040173818;684.9589024658437;700.8668353013013;723.6779633063342;740.8481261341412;8457.661693532313;9840.803183936425;9909.058639429242;9954.452505112144;10047.899521004696;504.90154419888506;513.3044724398461;534.1293691283183;654.0842820051965;677.4851113154039;3924.4309575111447;3946.210757848442;4061.1065565575527;4074.307367747431;4284.143171365739;57937;58651.70674146602;60705;61676.07485887626;61960.69019654873;50799.746001269756;52080.73959630178;52395.214071788956;52530.21204681933;54373.184402234;47479;48835.267470987965;49580;49892.50107498879;50284.999999999265;8844.159804818531;9270.212031977357;9354.71935841926;9435.952820235854;9572.473513956798;34952.475712864834;35986;36485.81757091304;36723.63276367202;36797.81601091977;43373.78313108414;43386.56613433825;45562.31656525221;46947.295790563854;47890.52109478861;11263.66209013731;11486.827697584376;11587.304761714324;11792.587259446222;11813.76372472563;13063.869361306266;13105.2136871788;13135.868641313848;13160.473581057033;13291.136076155019;11904.226225295328;11973.10201734856;12278.447469864057;12497.312647804458;12565;11211.159163062639;11341.26281791681;11383.658490245305;11848.28910265387;11940.522379105087;10379.377237365783;10836.837447438453;11062.668119956414;11082.61210857616;11195.10439164881;10288.588456461657;10905.127589792955;10936.179786516203;10938.45307734626;11112.888711128822;9938.801223975624;10101.14140298083;10166;10223.693289201336;10284.845727314247 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,739 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: __startTimestamp1453398547936__endTimestamp 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:49:07 INFO - 2016-01-21 09:49:07,946 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,947 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5466.64 Median:5685.63 stddev:593.62 (10.4%) stddev-sans-first:569.63 09:49:07 INFO - 2016-01-21 09:49:07,947 DEBUG : BROWSER_OUTPUT: Values: 3259.6 3331.5 3365.7 3370.9 3384.8 5416.9 5431.6 5433.5 5478.4 5494.2 5457.0 5499.8 5503.8 5507.2 5524.1 4894.7 4973.7 4981.2 5041.2 5066.8 4925.9 4982.5 4985.2 4987.6 4988.0 4853.4 4913.7 4976.8 4984.8 4994.4 4961.0 5058.7 5059.7 5064.4 5086.9 4789.6 4951.0 4960.2 4970.2 4998.4 5207.3 5219.8 5306.0 5306.3 5320.9 5875.4 5914.8 5916.3 5922.3 5931.4 5773.5 5890.0 5957.9 5959.9 5962.2 5450.1 5541.2 5543.5 5553.0 5582.4 6046.7 6073.3 6075.2 6114.0 6117.1 5511.4 5663.1 5695.8 5854.2 5881.8 5885.2 5918.7 5927.6 5929.1 5931.7 5839.0 5866.3 5880.7 5883.4 5887.5 5663.1 5754.6 5776.0 5881.6 5899.9 4539.9 4560.6 4581.8 4584.3 4613.6 4108.9 4119.4 4124.1 4126.5 4152.7 5475.6 5489.8 5568.4 5601.5 5703.1 5915.7 5939.5 5941.4 5943.1 5944.8 5580.9 5633.2 5636.8 5638.1 5665.1 4942.7 5077.7 5127.2 5199.4 5223.2 6064.3 6072.8 6089.1 6094.0 6106.6 5756.1 5937.1 5944.8 5950.2 5950.6 5810.4 5890.5 5915.8 5917.8 5931.9 5728.9 5775.0 5778.1 5783.0 5807.9 5708.0 5819.1 5883.8 5892.7 5903.5 5740.9 5795.7 5800.0 5800.2 5834.2 5796.9 5835.1 5843.2 5845.2 5848.7 5528.2 5656.1 5677.6 5697.6 5740.5 5763.5 5779.6 5780.7 5788.0 5793.1 5624.5 5680.7 5685.6 5686.1 5703.3 09:49:07 INFO - 2016-01-21 09:49:07,947 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,947 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56112.09 Median:3602.30 stddev:99678.20 (2767.1%) stddev-sans-first:99870.62 09:49:07 INFO - 2016-01-21 09:49:07,947 DEBUG : BROWSER_OUTPUT: Values: 578.8 614.9 632.1 633.7 687.3 31348.8 32584.2 40885.6 41121.4 41151.8 45270.0 45570.1 45652.3 45806.8 45827.5 8097.5 9076.3 9206.8 9235.7 9237.8 8124.8 10836.8 11021.7 11034.1 11039.0 10773.2 10895.7 10897.5 10906.5 10907.7 8300.9 8434.7 10696.3 10789.4 10818.7 10618.5 10767.8 10781.2 10785.5 10786.2 41473.4 41998.6 42002.0 42004.4 42018.6 3559.8 3576.4 3595.3 3644.1 3777.1 31739.2 33609.8 33678.7 33704.2 33751.7 3292.4 3510.9 3609.3 3611.2 3628.4 301104.0 301212.0 302790.0 302889.0 305042.0 65821.7 68060.0 68884.0 69022.7 69259.3 62077.7 81581.0 81904.0 82118.0 82267.6 18099.8 18441.5 18456.2 18459.0 18461.8 14125.9 14306.0 14332.9 14341.9 14343.2 22516.3 22989.5 23010.0 23041.5 23119.1 8807.2 8940.5 8956.9 9028.3 9044.4 1126.4 1146.9 1174.2 1238.5 1242.7 28899.3 29124.9 29193.6 29214.0 29460.0 1116.2 1134.2 1144.5 1204.0 1314.5 14192.8 14599.6 14907.1 17364.6 17430.8 419614.0 425600.0 426523.0 426669.0 427242.0 57582.0 58044.7 58099.7 58161.7 58169.3 1408.1 1410.4 1417.3 1448.2 1451.9 30719.7 30861.2 30926.5 31522.2 31547.8 51894.5 52498.7 52521.5 52557.0 52559.2 323607.0 328175.0 421675.0 426345.0 467131.0 45393.3 45662.4 45708.3 46231.8 46424.5 48386.3 48587.3 48820.8 49203.8 49842.0 39765.4 40594.0 40989.4 41020.6 41381.0 37744.8 42837.0 43439.3 43944.3 44828.3 38468.8 38721.8 43877.8 44792.3 44890.3 32996.5 33958.0 33997.0 34300.1 34922.8 26318.0 26732.7 26765.9 26819.5 27123.7 09:49:07 INFO - 2016-01-21 09:49:07,948 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,948 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31839.72 Median:18897.06 stddev:102674.72 (543.3%) stddev-sans-first:102935.56 09:49:07 INFO - 2016-01-21 09:49:07,949 DEBUG : BROWSER_OUTPUT: Values: 376.1 377.6 381.8 383.7 384.1 16842.7 19746.2 19959.5 20368.2 20406.3 17984.0 18058.5 18227.6 18422.4 18422.4 5567.7 5700.2 5700.7 5706.5 5710.5 5400.5 5552.4 5567.0 5609.8 5612.0 3989.1 4097.3 5526.5 5533.2 5535.3 5632.2 5772.5 5806.2 5811.0 5811.8 5572.4 5577.2 5588.0 5716.2 5724.1 12738.4 16991.2 17184.9 20101.8 20629.5 2101.7 2293.5 2312.4 2327.7 2436.5 9965.7 10107.5 10299.3 10390.8 10510.4 1883.5 1947.6 1990.3 1993.3 2035.6 66578.7 68837.0 69934.7 70454.0 70859.3 10999.3 11176.2 11176.4 11187.2 11278.0 14882.3 14995.6 15005.5 15026.0 15034.4 8596.2 8658.3 8660.8 8683.4 8697.4 8022.9 8023.0 8105.2 8184.5 8193.8 4609.4 4626.4 4659.4 4750.3 4871.5 1859.4 1870.6 1937.5 1939.1 1957.7 402.3 443.8 456.8 467.8 1012.3 10591.2 11679.7 11933.5 11970.2 11979.3 478.6 490.3 495.5 501.5 511.9 5554.1 6275.7 6292.0 6374.7 6382.0 610415.0 621959.0 627263.0 634594.0 642633.0 89427.6 91796.0 91805.5 91925.0 92023.0 523.5 526.1 530.9 532.1 532.5 10929.0 11110.7 11329.0 11520.5 15277.2 34362.8 36201.3 36607.5 37418.1 40229.8 43850.0 44099.3 46151.0 46479.3 49603.0 10084.0 10187.1 10263.0 10304.8 10309.7 11845.7 13692.0 18052.6 18118.1 18491.3 14980.8 15159.9 15591.8 15698.2 15732.0 15016.9 15081.8 15205.9 15562.2 15661.3 16629.0 16737.0 16784.8 16846.0 17089.9 9229.3 9263.1 9538.1 10345.0 10471.8 10472.4 10516.1 10661.3 16339.7 16503.8 09:49:07 INFO - 2016-01-21 09:49:07,949 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,949 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5329.59 Median:6149.89 stddev:2737.09 (44.5%) stddev-sans-first:2728.24 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: Values: 1327.4 1514.7 1556.9 1725.8 1842.9 6877.2 7453.7 7658.0 7693.7 7694.3 7289.8 7416.4 7416.9 7417.4 7420.0 2247.3 2267.2 2284.9 2287.1 2288.5 2310.5 2339.4 2361.4 2364.6 2365.4 2267.7 2286.9 2299.4 2301.7 2315.4 2254.2 2287.4 2317.7 2317.9 2319.8 2289.8 2314.0 2314.3 2316.8 2318.2 7672.9 7737.9 7737.9 7744.1 7746.8 4660.6 4728.0 4741.1 4777.2 4779.4 3062.4 3084.2 3149.3 3163.8 3192.4 983.1 1002.3 1003.3 1006.0 1009.5 10345.5 10395.5 10424.0 10429.1 10435.1 4312.0 4312.9 4342.7 4375.5 4390.8 8992.9 9012.7 9021.5 9030.0 9039.1 4825.5 4866.3 4882.9 4886.1 4891.7 3849.5 3918.2 4289.9 4433.0 4456.6 3744.2 3763.7 3779.2 3779.3 3804.4 1232.6 1238.3 1239.4 1242.7 1247.3 1246.4 1246.8 1249.8 1254.0 1282.4 6927.7 6941.4 6967.8 7064.9 7086.1 479.9 480.5 501.0 504.7 505.1 5212.7 5258.7 5265.4 5266.1 5277.2 8910.2 8937.0 9198.0 9210.5 9219.3 9114.9 9132.5 9147.3 9152.1 9162.1 7125.5 7240.7 7252.6 7290.9 7307.5 6950.2 6963.7 7003.2 7029.1 7040.4 7972.2 8293.8 8299.3 8310.8 8311.6 8318.9 8355.9 8593.3 8593.5 8604.8 5984.8 6147.1 6670.4 6908.4 6985.4 6152.7 6170.2 6186.2 6211.9 6214.7 6907.2 7056.4 7075.8 7168.5 7200.7 6912.6 6949.8 7004.3 7008.5 7071.0 6622.1 6772.7 6843.1 6897.2 6905.5 6204.8 6246.5 6263.9 6264.1 6270.5 6309.2 6362.4 6385.7 6387.9 6389.8 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5436.04 Median:6251.80 stddev:1636.88 (26.2%) stddev-sans-first:1596.38 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: Values: 352.6 352.8 354.2 364.1 364.4 5952.6 6031.0 6103.7 6142.9 6160.1 5873.3 5945.4 6035.9 6057.5 6061.9 4657.1 4672.8 4682.0 4689.0 4690.3 4475.4 4583.1 4669.1 4669.4 4679.8 4537.7 4639.4 4647.1 4649.6 4660.0 4629.3 4632.5 4643.5 4644.5 4656.9 4092.2 4093.1 4098.8 4099.3 4106.4 5628.2 5743.0 5743.6 5752.3 5754.5 5440.5 5441.1 5443.2 5448.8 5453.8 6437.7 6442.8 6444.0 6444.6 6449.4 4551.3 4566.0 4566.1 4568.1 4575.2 6567.0 6699.4 6699.7 6700.9 6706.6 4680.0 4680.9 4684.4 4685.3 4688.2 4744.0 4745.1 4745.3 4746.9 4749.2 5242.4 5417.7 5425.8 5425.8 5439.5 6485.5 6506.4 6517.9 6527.7 6528.4 1618.7 1634.2 1634.7 1636.0 1639.1 1051.9 1055.9 1070.0 1073.2 1086.9 6247.0 6261.4 6262.4 6266.6 6269.8 6567.0 6601.4 6601.7 6611.3 6619.6 6256.6 6322.7 6337.8 6364.3 6432.0 3605.1 3634.4 3637.2 3643.3 3645.5 6763.2 6891.6 6908.8 6917.0 6921.7 6898.0 6908.7 6911.1 6914.2 6919.3 6917.7 6918.0 6921.5 6922.1 6925.1 6622.3 6674.4 6748.5 6750.2 6758.2 6631.8 6634.5 6638.9 6639.4 6640.6 6907.0 6919.2 6924.7 6924.8 6934.8 4568.4 4660.4 4660.4 4667.3 4669.7 6717.5 6727.9 6730.3 6738.2 6739.7 6565.7 6565.8 6566.7 6566.8 6568.5 6440.8 6550.7 6559.5 6562.7 6565.3 6439.3 6450.5 6455.7 6458.9 6459.7 6561.1 6569.0 6572.0 6573.8 6579.3 6298.4 6415.6 6423.6 6427.7 6431.0 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14536.25 Median:43380.17 stddev:16609.64 (38.3%) stddev-sans-first:16624.22 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: Values: 804.1 850.7 919.9 921.4 952.0 13982.0 16037.2 16378.8 16385.3 16450.4 15363.3 15421.9 15435.5 15463.5 15470.6 730.9 740.2 872.4 876.8 881.5 892.8 893.4 900.5 901.0 901.3 934.8 942.5 942.9 943.4 943.7 771.7 783.5 914.8 927.1 928.0 693.7 694.1 694.4 694.4 694.4 13911.6 14003.4 14011.6 14017.0 14033.4 12057.3 12282.9 13056.8 13281.7 13541.3 10760.8 10775.0 10856.5 10860.5 11716.2 3883.2 3896.9 3970.1 3976.5 3991.7 50381.2 50658.0 51301.0 51439.0 52848.2 10444.4 10626.7 10764.7 10814.1 10829.0 18512.5 18518.0 18554.9 18576.5 18596.9 7556.1 7579.2 7733.8 7824.3 7895.4 4620.4 4742.3 4818.6 4871.9 4899.1 3272.8 3312.4 3318.0 3318.7 3319.8 576.9 597.6 602.7 605.3 605.9 679.1 685.0 700.9 723.7 740.8 8457.7 9840.8 9909.1 9954.5 10047.9 504.9 513.3 534.1 654.1 677.5 3924.4 3946.2 4061.1 4074.3 4284.1 57937.0 58651.7 60705.0 61676.1 61960.7 50799.7 52080.7 52395.2 52530.2 54373.2 47479.0 48835.3 49580.0 49892.5 50285.0 8844.2 9270.2 9354.7 9436.0 9572.5 34952.5 35986.0 36485.8 36723.6 36797.8 43373.8 43386.6 45562.3 46947.3 47890.5 11263.7 11486.8 11587.3 11792.6 11813.8 13063.9 13105.2 13135.9 13160.5 13291.1 11904.2 11973.1 12278.4 12497.3 12565.0 11211.2 11341.3 11383.7 11848.3 11940.5 10379.4 10836.8 11062.7 11082.6 11195.1 10288.6 10905.1 10936.2 10938.5 11112.9 9938.8 10101.1 10166.0 10223.7 10284.8 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:49:07 INFO - 2016-01-21 09:49:07,951 DEBUG : BROWSER_OUTPUT: 09:49:08 INFO - 2016-01-21 09:49:08,622 INFO : Browser exited with error code: 0 09:49:08 INFO - 2016-01-21 09:49:08,643 INFO : Completed test dromaeo_css (00:18:30) 09:49:08 INFO - 2016-01-21 09:49:08,643 INFO : Starting test kraken 09:49:08 INFO - 2016-01-21 09:49:08,643 DEBUG : operating with platform_type : linux_ 09:49:08 INFO - 2016-01-21 09:49:08,643 INFO : Initialising browser for kraken test... 09:49:08 INFO - 2016-01-21 09:49:08,649 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpROA9tW/profile http://localhost:57275/getInfo.html 09:49:12 INFO - 2016-01-21 09:49:12,393 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:49:12 INFO - 2016-01-21 09:49:12,393 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:49:12 INFO - 2016-01-21 09:49:12,393 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:49:12 INFO - 2016-01-21 09:49:12,393 DEBUG : BROWSER_OUTPUT: __metrics 09:49:12 INFO - 2016-01-21 09:49:12,530 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] 09:49:13 INFO - 2016-01-21 09:49:13,569 INFO : Browser initialized. 09:49:13 INFO - 2016-01-21 09:49:13,570 INFO : Running cycle 1/1 for kraken test... 09:49:13 INFO - 2016-01-21 09:49:13,570 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpROA9tW/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:49:25 INFO - 2016-01-21 09:49:25,374 DEBUG : BROWSER_OUTPUT: RSS: Main: 137654272 09:49:25 INFO - 2016-01-21 09:49:25,375 DEBUG : BROWSER_OUTPUT: 09:49:27 INFO - 2016-01-21 09:49:27,834 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57275/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:28 INFO - 2016-01-21 09:49:28,416 DEBUG : BROWSER_OUTPUT: RSS: Main: 168730624 09:49:28 INFO - 2016-01-21 09:49:28,416 DEBUG : BROWSER_OUTPUT: 09:49:31 INFO - 2016-01-21 09:49:31,001 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57275/tests/kraken/driver.html?testName=audio-dft) 09:49:31 INFO - 2016-01-21 09:49:31,567 DEBUG : BROWSER_OUTPUT: RSS: Main: 167174144 09:49:31 INFO - 2016-01-21 09:49:31,567 DEBUG : BROWSER_OUTPUT: 09:49:34 INFO - 2016-01-21 09:49:34,369 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57275/tests/kraken/driver.html?testName=audio-fft) 09:49:34 INFO - 2016-01-21 09:49:34,936 DEBUG : BROWSER_OUTPUT: RSS: Main: 164397056 09:49:34 INFO - 2016-01-21 09:49:34,936 DEBUG : BROWSER_OUTPUT: 09:49:36 INFO - 2016-01-21 09:49:36,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57275/tests/kraken/driver.html?testName=audio-oscillator) 09:49:37 INFO - 2016-01-21 09:49:37,489 DEBUG : BROWSER_OUTPUT: RSS: Main: 164892672 09:49:37 INFO - 2016-01-21 09:49:37,490 DEBUG : BROWSER_OUTPUT: 09:49:39 INFO - 2016-01-21 09:49:39,664 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:40 INFO - 2016-01-21 09:49:40,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 163745792 09:49:40 INFO - 2016-01-21 09:49:40,225 DEBUG : BROWSER_OUTPUT: 09:49:43 INFO - 2016-01-21 09:49:43,063 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57275/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:43 INFO - 2016-01-21 09:49:43,629 DEBUG : BROWSER_OUTPUT: RSS: Main: 163762176 09:49:43 INFO - 2016-01-21 09:49:43,629 DEBUG : BROWSER_OUTPUT: 09:49:46 INFO - 2016-01-21 09:49:46,998 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57275/tests/kraken/driver.html?testName=imaging-desaturate) 09:49:47 INFO - 2016-01-21 09:49:47,564 DEBUG : BROWSER_OUTPUT: RSS: Main: 163876864 09:49:47 INFO - 2016-01-21 09:49:47,564 DEBUG : BROWSER_OUTPUT: 09:49:50 INFO - 2016-01-21 09:49:50,247 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57275/tests/kraken/driver.html?testName=json-parse-financial) 09:49:50 INFO - 2016-01-21 09:49:50,816 DEBUG : BROWSER_OUTPUT: RSS: Main: 163868672 09:49:50 INFO - 2016-01-21 09:49:50,816 DEBUG : BROWSER_OUTPUT: 09:49:52 INFO - 2016-01-21 09:49:52,371 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:52 INFO - 2016-01-21 09:49:52,933 DEBUG : BROWSER_OUTPUT: RSS: Main: 170475520 09:49:52 INFO - 2016-01-21 09:49:52,933 DEBUG : BROWSER_OUTPUT: 09:49:54 INFO - 2016-01-21 09:49:54,675 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:55 INFO - 2016-01-21 09:49:55,265 DEBUG : BROWSER_OUTPUT: RSS: Main: 167215104 09:49:55 INFO - 2016-01-21 09:49:55,265 DEBUG : BROWSER_OUTPUT: 09:49:57 INFO - 2016-01-21 09:49:57,264 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:57 INFO - 2016-01-21 09:49:57,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 177930240 09:49:57 INFO - 2016-01-21 09:49:57,838 DEBUG : BROWSER_OUTPUT: 09:50:00 INFO - 2016-01-21 09:50:00,212 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:00 INFO - 2016-01-21 09:50:00,779 DEBUG : BROWSER_OUTPUT: RSS: Main: 183619584 09:50:00 INFO - 2016-01-21 09:50:00,779 DEBUG : BROWSER_OUTPUT: 09:50:03 INFO - 2016-01-21 09:50:03,266 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:03 INFO - 2016-01-21 09:50:03,839 DEBUG : BROWSER_OUTPUT: RSS: Main: 182820864 09:50:03 INFO - 2016-01-21 09:50:03,839 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57275/tests/kraken/driver.html?testName=ai-astar) 09:50:05 INFO - 2016-01-21 09:50:05,752 DEBUG : BROWSER_OUTPUT: RSS: Main: 241422336 09:50:05 INFO - 2016-01-21 09:50:05,752 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,756 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:50:05 INFO - 2016-01-21 09:50:05,756 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:50:05 INFO - 2016-01-21 09:50:05,756 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:50:05 INFO - 2016-01-21 09:50:05,756 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:50:05 INFO - 2016-01-21 09:50:05,756 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;104;109;109;110;98;109;109;110 09:50:05 INFO - 2016-01-21 09:50:05,756 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;131;146;170;130;143;153;126;166;129 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;161;158;160;160;159;160;167;159 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |3;audio-fft;78;71;71;156;71;72;72;70;158;71 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;139;93;92;92;138;92;93;134 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;109;109;109;110;109;109 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;145;145;145;145;146;146;146;146;146 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;94;95;95;95;96;95 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;57;57;57;57;57;57;56;55;55 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;64;63;64;64;64;64;63;64;63 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;80;79;82;80;81;80;80;81;81;81 09:50:05 INFO - 2016-01-21 09:50:05,757 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;118;116;129;112;111;117;126;124;110 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;144;143;146;143;135;132;153;143;141 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;66;67;66;67;64;65;62;67;64 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,896 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: __startTimestamp1453398605754__endTimestamp 09:50:05 INFO - 2016-01-21 09:50:05,758 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,761 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:50:05 INFO - 2016-01-21 09:50:05,761 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:50:05 INFO - 2016-01-21 09:50:05,761 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,761 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.60 Median:109.00 stddev:3.78 (3.5%) stddev-sans-first:3.97 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 104.0 109.0 109.0 110.0 98.0 109.0 109.0 110.0 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.00 Median:146.00 stddev:15.51 (10.6%) stddev-sans-first:16.43 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: Values: 146.0 131.0 146.0 170.0 130.0 143.0 153.0 126.0 166.0 129.0 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:2.46 (1.5%) stddev-sans-first:2.60 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 161.0 158.0 160.0 160.0 159.0 160.0 167.0 159.0 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.00 Median:71.00 stddev:35.91 (50.6%) stddev-sans-first:37.87 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: Values: 78.0 71.0 71.0 156.0 71.0 72.0 72.0 70.0 158.0 71.0 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.90 Median:92.50 stddev:21.50 (23.2%) stddev-sans-first:22.29 09:50:05 INFO - 2016-01-21 09:50:05,762 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 139.0 93.0 92.0 92.0 138.0 92.0 93.0 134.0 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 109.0 109.0 109.0 110.0 109.0 109.0 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.53 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: Values: 148.0 145.0 145.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 09:50:05 INFO - 2016-01-21 09:50:05,763 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:1.14 (2.0%) stddev-sans-first:0.88 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: Values: 54.0 57.0 57.0 57.0 57.0 57.0 57.0 56.0 55.0 55.0 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.50 Median:64.00 stddev:0.71 (1.1%) stddev-sans-first:0.50 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: Values: 62.0 64.0 63.0 64.0 64.0 64.0 64.0 63.0 64.0 63.0 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:81.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: Values: 80.0 79.0 82.0 80.0 81.0 80.0 80.0 81.0 81.0 81.0 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.90 Median:117.50 stddev:6.49 (5.5%) stddev-sans-first:6.85 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: Values: 116.0 118.0 116.0 129.0 112.0 111.0 117.0 126.0 124.0 110.0 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.50 Median:143.00 stddev:6.15 (4.3%) stddev-sans-first:6.06 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: Values: 135.0 144.0 143.0 146.0 143.0 135.0 132.0 153.0 143.0 141.0 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: 09:50:05 INFO - 2016-01-21 09:50:05,764 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:66.00 stddev:1.79 (2.7%) stddev-sans-first:1.73 09:50:05 INFO - 2016-01-21 09:50:05,765 DEBUG : BROWSER_OUTPUT: Values: 63.0 66.0 67.0 66.0 67.0 64.0 65.0 62.0 67.0 64.0 09:50:05 INFO - 2016-01-21 09:50:05,765 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:50:05 INFO - 2016-01-21 09:50:05,765 DEBUG : BROWSER_OUTPUT: 09:50:06 INFO - 2016-01-21 09:50:06,514 INFO : Browser exited with error code: 0 09:50:06 INFO - 2016-01-21 09:50:06,565 INFO : Completed test kraken (00:00:57) 09:50:06 INFO - 2016-01-21 09:50:06,565 INFO : Starting test v8_7 09:50:06 INFO - 2016-01-21 09:50:06,565 DEBUG : operating with platform_type : linux_ 09:50:06 INFO - 2016-01-21 09:50:06,565 INFO : Initialising browser for v8_7 test... 09:50:06 INFO - 2016-01-21 09:50:06,571 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRIiwLI/profile http://localhost:57275/getInfo.html 09:50:10 INFO - 2016-01-21 09:50:10,217 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:50:10 INFO - 2016-01-21 09:50:10,217 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:50:10 INFO - 2016-01-21 09:50:10,217 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:50:10 INFO - 2016-01-21 09:50:10,217 DEBUG : BROWSER_OUTPUT: __metrics 09:50:10 INFO - 2016-01-21 09:50:10,333 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] 09:50:11 INFO - 2016-01-21 09:50:11,392 INFO : Browser initialized. 09:50:11 INFO - 2016-01-21 09:50:11,392 INFO : Running cycle 1/1 for v8_7 test... 09:50:11 INFO - 2016-01-21 09:50:11,392 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRIiwLI/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:50:23 INFO - 2016-01-21 09:50:23,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 137420800 09:50:23 INFO - 2016-01-21 09:50:23,181 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,558 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57275/tests/v8_7/run.html (next: http://localhost:57275/tests/v8_7/run.html) 09:50:44 INFO - 2016-01-21 09:50:44,808 DEBUG : BROWSER_OUTPUT: RSS: Main: 424345600 09:50:44 INFO - 2016-01-21 09:50:44,808 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,808 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:50:44 INFO - 2016-01-21 09:50:44,808 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:50:44 INFO - 2016-01-21 09:50:44,809 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:50:44 INFO - 2016-01-21 09:50:44,809 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:50:44 INFO - 2016-01-21 09:50:44,809 DEBUG : BROWSER_OUTPUT: |0;Richards;168.20857863751053 09:50:44 INFO - 2016-01-21 09:50:44,809 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;173.94329448599757 09:50:44 INFO - 2016-01-21 09:50:44,809 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.09572983533354 09:50:44 INFO - 2016-01-21 09:50:44,810 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5208.333333333333 09:50:44 INFO - 2016-01-21 09:50:44,810 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1002.0040080160321 09:50:44 INFO - 2016-01-21 09:50:44,810 DEBUG : BROWSER_OUTPUT: |5;Earley;558.3472920156337 09:50:44 INFO - 2016-01-21 09:50:44,810 DEBUG : BROWSER_OUTPUT: |6;Boyer;11109.89010989011 09:50:44 INFO - 2016-01-21 09:50:44,810 DEBUG : BROWSER_OUTPUT: |7;RegExp;23928.571428571428 09:50:44 INFO - 2016-01-21 09:50:44,810 DEBUG : BROWSER_OUTPUT: |8;Splay;516.2622612287042 09:50:44 INFO - 2016-01-21 09:50:44,811 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 09:50:44 INFO - 2016-01-21 09:50:44,811 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:50:44 INFO - 2016-01-21 09:50:44,811 DEBUG : BROWSER_OUTPUT: __startTimestamp1453398644806__endTimestamp 09:50:44 INFO - 2016-01-21 09:50:44,811 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,811 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:50:44 INFO - 2016-01-21 09:50:44,811 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:50:44 INFO - 2016-01-21 09:50:44,812 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,812 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.21 Median:168.21 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,812 DEBUG : BROWSER_OUTPUT: Values: 168.2 09:50:44 INFO - 2016-01-21 09:50:44,812 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,812 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:173.94 Median:173.94 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,812 DEBUG : BROWSER_OUTPUT: Values: 173.9 09:50:44 INFO - 2016-01-21 09:50:44,813 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,813 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,813 DEBUG : BROWSER_OUTPUT: Values: 279.1 09:50:44 INFO - 2016-01-21 09:50:44,813 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,813 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,813 DEBUG : BROWSER_OUTPUT: Values: 5208.3 09:50:44 INFO - 2016-01-21 09:50:44,814 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,814 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1002.00 Median:1002.00 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,814 DEBUG : BROWSER_OUTPUT: Values: 1002.0 09:50:44 INFO - 2016-01-21 09:50:44,814 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,815 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:558.35 Median:558.35 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: Values: 558.3 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11109.89 Median:11109.89 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: Values: 11109.9 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23928.57 Median:23928.57 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: Values: 23928.6 09:50:44 INFO - 2016-01-21 09:50:44,816 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,817 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:516.26 Median:516.26 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,817 DEBUG : BROWSER_OUTPUT: Values: 516.3 09:50:44 INFO - 2016-01-21 09:50:44,817 DEBUG : BROWSER_OUTPUT: 09:50:44 INFO - 2016-01-21 09:50:44,817 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 09:50:44 INFO - 2016-01-21 09:50:44,817 DEBUG : BROWSER_OUTPUT: Values: 4840.6 09:50:44 INFO - 2016-01-21 09:50:44,817 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:50:44 INFO - 2016-01-21 09:50:44,817 DEBUG : BROWSER_OUTPUT: 09:50:45 INFO - 2016-01-21 09:50:45,629 INFO : Browser exited with error code: 0 09:50:45 INFO - 2016-01-21 09:50:45,646 INFO : Completed test v8_7 (00:00:39) 09:50:45 INFO - 2016-01-21 09:50:45,821 INFO : Completed test suite (00:20:07) 09:50:45 INFO - 2016-01-21 09:50:45,822 DEBUG : Working with test: dromaeo_css 09:50:45 INFO - 2016-01-21 09:50:45,822 DEBUG : Generating results file: dromaeo_css 09:50:45 INFO - 2016-01-21 09:50:45,823 DEBUG : Working with test: kraken 09:50:45 INFO - 2016-01-21 09:50:45,823 DEBUG : Generating results file: kraken 09:50:45 INFO - 2016-01-21 09:50:45,823 INFO : javascript benchmark 09:50:45 INFO - 2016-01-21 09:50:45,823 DEBUG : Working with test: v8_7 09:50:45 INFO - 2016-01-21 09:50:45,824 DEBUG : Generating results file: v8_7 09:50:45 INFO - 2016-01-21 09:50:45,824 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:50:45 INFO - 2016-01-21 09:50:45,950 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 09:50:45 INFO - 2016-01-21 09:50:45,950 DEBUG : process_Request line: dromaeo_css 7201.86 graph.html#tests=[[72,52,35]] 09:50:45 INFO - 2016-01-21 09:50:45,950 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:50:45 INFO - 2016-01-21 09:50:45,987 DEBUG : process_Request line: kraken 1481.80 graph.html#tests=[[232,52,35]] 09:50:45 INFO - 2016-01-21 09:50:45,987 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:50:46 INFO - 2016-01-21 09:50:46,089 DEBUG : process_Request line: v8_7 22882.93 graph.html#tests=[[230,52,35]] 09:50:46 INFO - 2016-01-21 09:50:46,091 INFO : javascript benchmark 09:50:46 INFO - 2016-01-21 09:50:46,095 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3259.5762550868385, 3331.516930045145, 3365.663433656633, 3370.949435758464, 3384.847681854317, 5416.918746218805, 5431.619786614933, 5433.4566543345645, 5478.369987451444, 5494.230807686917, 5456.972715136429, 5499.835004949848, 5503.7798488060425, 5507.173923911415, 5524.088525393306, 4894.657373983822, 4973.676711013782, 4981.227909674007, 5041.1682072458, 5066.797328106872, 4925.852224433273, 4982.476839931809, 4985.202367621163, 4987.551120399164, 4987.950120498803, 4853.39332583427, 4913.680610760307, 4976.751162441881, 4984.75076246188, 4994.400671919375, 4960.950390496085, 5058.747062646853, 5059.747012649353, 5064.366954130752, 5086.898262034775, 4789.592884604778, 4950.9504904950845, 4960.156773348539, 4970.179920313144, 4998.400191976986, 5207.297014902978, 5219.791208351661, 5306.0, 5306.336707911511, 5320.920186197211, 5875.441833025856, 5914.763409463616, 5916.290045194562, 5922.3485416604135, 5931.377205393414, 5773.509251713609, 5889.970550147222, 5957.91063134049, 5959.940400596025, 5962.165296858443, 5450.073487507133, 5541.196526503634, 5543.473370029841, 5552.972235138839, 5582.385937546866, 6046.727897244635, 6073.331933487313, 6075.2405949256345, 6114.0, 6117.143599896018, 5511.448855114457, 5663.093904975184, 5695.772169113272, 5854.180414741854, 5881.823545293648, 5885.2349194605285, 5918.674472904032, 5927.555433342516, 5929.14027466015, 5931.673757943268, 5839.007368747323, 5866.266716660418, 5880.705964701748, 5883.441073098049, 5887.499562537232, 5663.065594176915, 5754.597178197486, 5775.971120144456, 5881.617694849821, 5899.911501327483, 4539.886502837456, 4560.5895469407915, 4581.816727330869, 4584.312353147055, 4613.63090952723, 4108.938365924513, 4119.382092686121, 4124.05146816234, 4126.484189476316, 4152.667786577066, 5475.616706830563, 5489.752961116759, 5568.387477377485, 5601.5238704709745, 5703.144528320785, 5915.704214789243, 5939.495142912814, 5941.37615550361, 5943.138244954457, 5944.762209511571, 5580.86047848807, 5633.211350410946, 5636.774529018793, 5638.069718496484, 5665.065264231355, 4942.654014218971, 5077.669951453144, 5127.154019586801, 5199.350081239845, 5223.190405487156, 6064.272287325461, 6072.757089716361, 6089.147519347295, 6093.994490909039, 6106.603070800384, 5756.136579513107, 5937.109433584824, 5944.791932282349, 5950.16697662319, 5950.553708471795, 5810.360860305447, 5890.4993075589055, 5915.763369465173, 5917.792877249276, 5931.881362372815, 5728.923617661295, 5775.0, 5778.133280008033, 5783.0, 5807.94192058074, 5708.0, 5819.127130930395, 5883.764649413975, 5892.6759028253855, 5903.498202652822, 5740.942590574041, 5795.681237531976, 5799.971000144972, 5800.1589769484035, 5834.18321435008, 5796.884062318814, 5835.066389377679, 5843.181954526454, 5845.152452894392, 5848.707564621837, 5528.170774383874, 5656.0667489865355, 5677.630953987978, 5697.6296540724725, 5740.454656807597, 5763.452472015152, 5779.624324418899, 5780.653160810365, 5788.0, 5793.102069179284, 5624.465675760796, 5680.6591604503865, 5685.630434021776, 5686.09022556389, 5703.258576385209], "unit": "ms", "name": "dojo.html", "value": 5429.3008993330395}, {"lowerIsBetter": false, "replicates": [578.8089930322993, 614.9262088549375, 632.099258556557, 633.7306644676012, 687.2680595166148, 31348.84325578372, 32584.185395365126, 40885.59114408855, 41121.383179252334, 41151.79424102883, 45270.0, 45570.088598228016, 45652.315215271796, 45806.7709661452, 45827.54172458274, 8097.473664211837, 9076.273898088137, 9206.76983075425, 9235.676751313706, 9237.769055773591, 8124.796880077986, 10836.783264334708, 11021.724456888562, 11034.062104721108, 11039.0, 10773.192010599236, 10895.727606809814, 10897.509612067437, 10906.5092070857, 10907.672769816918, 8300.916990830076, 8434.662613495453, 10696.304740191863, 10789.352638841694, 10818.67543973678, 10618.469076546218, 10767.784644307148, 10781.191410644233, 10785.460726963698, 10786.191035672357, 41473.377899331535, 41998.580014199775, 42002.0, 42004.36993445101, 42018.579814201774, 3559.7864128152396, 3576.409892367782, 3595.26297109092, 3644.0707619556842, 3777.131259810259, 31739.206519836956, 33609.83195084034, 33678.6632133678, 33704.15739606505, 33751.662483375345, 3292.3579901919134, 3510.894673159784, 3609.3322735294055, 3611.1694310308512, 3628.38317486028, 301104.0, 301212.0, 302790.0, 302889.0, 305042.0, 65821.67089164523, 68060.0, 68884.0, 69022.65488672524, 69259.3074069263, 62077.68961155256, 81581.0, 81904.0, 82118.0, 82267.58866205631, 18099.819001810076, 18441.5389615258, 18456.169472373775, 18459.0, 18461.815381846278, 14125.85874141266, 14306.0, 14332.856671433361, 14341.85658143426, 14343.211123388106, 22516.32451026472, 22989.540209195722, 23010.0, 23041.53916921652, 23119.07523699067, 8807.207351338411, 8940.50827204497, 8956.910430895608, 9028.310629689706, 9044.366894317334, 1126.357975953715, 1146.9139814513778, 1174.2074099982478, 1238.5417395563518, 1242.6644805902356, 28899.277518061797, 29124.854375727984, 29193.56209656857, 29213.974015915734, 29460.0, 1116.2356121901305, 1134.22872446737, 1144.5250221158126, 1203.957861474844, 1314.4610709609015, 14192.848865258482, 14599.562013139623, 14907.105573665614, 17364.565885852953, 17430.82569174292, 419614.0, 425600.0, 426523.0, 426669.0, 427242.0, 57582.0, 58044.70977645085, 58099.709501453064, 58161.709191453774, 58169.2549223524, 1408.126961284031, 1410.4005797536006, 1417.2701058954647, 1448.167303800298, 1451.8693317601467, 30719.692803072576, 30861.22846928755, 30926.53610195894, 31522.211944700648, 31547.842260788548, 51894.481055190474, 52498.73750631375, 52521.47478525166, 52557.0, 52559.21161182662, 323607.0, 328175.0, 421675.0, 426345.0, 467131.0, 45393.31910021418, 45662.40181593628, 45708.314375283735, 46231.76884115558, 46424.53575464202, 48386.27420588765, 48587.27119093287, 48820.75589622029, 49203.778004833795, 49842.0, 39765.403518947816, 40594.0, 40989.38515922323, 41020.589794101674, 41381.0, 37744.811275943444, 42837.0, 43439.34840977451, 43944.34083488814, 44828.32757508705, 38468.807655961544, 38721.80639096787, 43877.78061109674, 44792.328115078955, 44890.326645099696, 32996.50505242471, 33958.0, 33997.0, 34300.14249643679, 34922.82538587291, 26318.0, 26732.732672673024, 26765.86617066902, 26819.463610727285, 27123.72876271212], "unit": "ms", "name": "ext.html", "value": 20986.91139925577}, {"lowerIsBetter": false, "replicates": [376.0579747731931, 377.62615011138973, 381.759074234245, 383.7045474984262, 384.1031192166292, 16842.747358789613, 19746.210151593918, 19959.501012474695, 20368.185272589097, 20406.285779997703, 17984.0, 18058.548536286566, 18227.635447291046, 18422.44732658019, 18422.447326580223, 5567.66594004361, 5700.230468886689, 5700.714964251792, 5706.486416222539, 5710.543156547466, 5400.459954004607, 5552.444755524456, 5566.972165139168, 5609.775608975636, 5612.0, 3989.0825110224664, 4097.323941549641, 5526.530244929186, 5533.197686335479, 5535.308086489189, 5632.211490391349, 5772.48047675707, 5806.245188125537, 5810.970945145289, 5811.82564523065, 5572.3870374258795, 5577.163425486169, 5588.0, 5716.171155182476, 5724.141378793214, 12738.426770795337, 16991.235394407282, 17184.914075429668, 20101.79898201014, 20629.4842628934, 2101.674240492726, 2293.5183611441616, 2312.387217387394, 2327.7267763377704, 2436.5248776488593, 9965.65120220796, 10107.545160467796, 10299.27905046647, 10390.792184156275, 10510.421926794023, 1883.5479484923658, 1947.6104779044106, 1990.3033938121687, 1993.2923812046804, 2035.6335859545277, 66578.66710666464, 68837.0, 69934.65032674855, 70454.0, 70859.29140708578, 10999.34003959765, 11176.161787865942, 11176.441177941071, 11187.160962927814, 11278.041366483776, 14882.255887205813, 14995.550133496012, 15005.549833505012, 15026.0, 15034.398624054915, 8596.183362580545, 8658.307335413152, 8660.78348041304, 8683.435576687496, 8697.391182617155, 8022.919770802334, 8022.959885200653, 8105.230003149692, 8184.508929464251, 8193.79515512117, 4609.400777898852, 4626.37543931565, 4659.370984917061, 4750.311204875274, 4871.488493708181, 1859.386402487176, 1870.560418301698, 1937.5059359863144, 1939.0983192815409, 1957.6574099532809, 402.25984189092213, 443.76480465353416, 456.77095772629355, 467.77780554236466, 1012.336919317848, 10591.205659575562, 11679.708007299885, 11933.462994165284, 11970.22193557416, 11979.34113623759, 478.6147151543041, 490.2621554560364, 495.47232197709326, 501.50601657367463, 511.91984911835993, 5554.0835762099605, 6275.6862156892885, 6292.014515457365, 6374.681265936684, 6381.968090159519, 610415.0, 621959.0, 627263.0, 634594.0, 642633.0, 89427.55286223657, 91796.0, 91805.54097229471, 91925.0, 92023.0, 523.4739087217322, 526.1345087331318, 530.8964751873425, 532.1326238231658, 532.4675324675325, 10929.0, 11110.722231944266, 11329.0, 11520.539178433104, 15277.236138193268, 34362.82818585891, 36201.275974480886, 36607.45088823723, 37418.06454838651, 40229.79885100556, 43850.0, 44099.338509921734, 46151.0, 46479.30281045855, 49603.0, 10084.0, 10187.13409360183, 10263.0, 10304.793904121725, 10309.74225644365, 11845.703857403634, 13692.0, 18052.63894722072, 18118.09409529492, 18491.260349585868, 14980.775288370902, 15159.924200378928, 15591.76612350793, 15698.215089245721, 15732.0, 15016.924915375354, 15081.849181508045, 15205.923970380078, 15562.221888905737, 15661.295241713922, 16629.0, 16737.0, 16784.832151678816, 16846.0, 17089.914550427664, 9229.307801915018, 9263.120003599648, 9538.14156725898, 10345.017223363768, 10471.842922356018, 10472.394994239317, 10516.106130978647, 10661.253712240068, 16339.673206536041, 16503.752443713114], "unit": "ms", "name": "jquery.html", "value": 8586.233034691675}, {"lowerIsBetter": false, "replicates": [1327.356232227369, 1514.7046325966437, 1556.9065856048642, 1725.7929048514172, 1842.889426634403, 6877.243503214642, 7453.66458509367, 7657.9617101914555, 7693.692252309908, 7694.345980591643, 7289.817754556138, 7416.406687465003, 7416.925830741691, 7417.406607471403, 7419.962900185505, 2247.2808701215617, 2267.1724820440586, 2284.931452056441, 2287.0965968442456, 2288.485090854561, 2310.514791893696, 2339.415146213447, 2361.3624321433203, 2364.6453032045247, 2365.3968238099346, 2267.7051983242154, 2286.9199578014773, 2299.367673889685, 2301.6777651128855, 2315.3748487908256, 2254.211026140854, 2287.3709729824263, 2317.6639387288833, 2317.88410579472, 2319.7680231976838, 2289.8168146548237, 2314.00497785952, 2314.305708287524, 2316.7567405422355, 2318.211807985276, 7672.884906726348, 7737.9226207737765, 7737.9226207737765, 7744.070711514598, 7746.845063098763, 4660.603848672867, 4727.95272047282, 4741.051789642051, 4777.235642297216, 4779.354787103731, 3062.433449811792, 3084.228942764309, 3149.2599239179003, 3163.7943533670236, 3192.4413227685063, 983.1004630762843, 1002.3334482569082, 1003.2726273451689, 1006.0241565681278, 1009.5457044330008, 10345.534450949724, 10395.532201050972, 10424.009719076676, 10429.061384475357, 10435.113015393701, 4311.956880431218, 4312.892177695583, 4342.69601127918, 4375.518692943837, 4390.846320378771, 8992.8651070234, 9012.684556040507, 9021.548922553846, 9030.0, 9039.050899655573, 4825.4933232010835, 4866.29438731389, 4882.853514394574, 4886.120498310267, 4891.6575839691595, 3849.480320156799, 3918.1967696622123, 4289.871303860889, 4432.977835110803, 4456.64346852251, 3744.194998075427, 3763.6612704856693, 3779.22525882199, 3779.281936432069, 3804.3722785740597, 1232.648695121886, 1238.2632333761399, 1239.3803098450774, 1242.726600147984, 1247.2641141726424, 1246.4328730427633, 1246.8067449545335, 1249.8312727781815, 1254.0155977557574, 1282.4485471247453, 6927.7228910844005, 6941.444684425335, 6967.756128535477, 7064.858702825914, 7086.149662040588, 479.86323897689687, 480.45468393373073, 500.964932454732, 504.7299694663411, 505.13621706881645, 5212.739363031833, 5258.710770907637, 5265.420803711589, 5266.052110620049, 5277.182036784305, 8910.242629376446, 8937.0, 9198.0, 9210.539473026322, 9219.308551858638, 9114.86327705085, 9132.543372831331, 9147.2682185425, 9152.130547597968, 9162.083791620784, 7125.5368401053765, 7240.674169662378, 7252.5648461092505, 7290.927090729131, 7307.451941104332, 6950.16598008222, 6963.651817409211, 7003.229644739176, 7029.121359830021, 7040.3663670269925, 7972.202779721982, 8293.792655183668, 8299.253067223979, 8310.83378332442, 8311.584420779058, 8318.875216871631, 8355.874661879956, 8593.269572086443, 8593.52735599523, 8604.78488037779, 5984.790532331347, 6147.0779383092895, 6670.39966403026, 6908.412784913338, 6985.3713165815325, 6152.692365381624, 6170.228721409824, 6186.1648677427975, 6211.875762484635, 6214.658193799384, 6907.240203577703, 7056.400205982549, 7075.752348667772, 7168.498205125591, 7200.675969581486, 6912.585244885323, 6949.791506254821, 7004.299570042955, 7008.544444611084, 7071.010058591904, 6622.139121914121, 6772.729090836311, 6843.144606924135, 6897.241303456515, 6905.516613836983, 6204.81385558434, 6246.531510136667, 6263.906041409291, 6264.060390941395, 6270.4670103039925, 6309.242890853127, 6362.395572420613, 6385.7126429309865, 6387.8722425550295, 6389.7763578274535], "unit": "ms", "name": "mootools.html", "value": 4346.374811653829}, {"lowerIsBetter": false, "replicates": [352.5733862026948, 352.75307284900566, 354.1588726773912, 364.12609736632095, 364.3987420755751, 5952.642841429517, 6031.0, 6103.694815259236, 6142.877142457148, 6160.106784516242, 5873.324567674718, 5945.37573554777, 6035.939640603593, 6057.454829065383, 6061.909071363933, 4657.091867085919, 4672.836450724225, 4682.016776476944, 4688.9765551172195, 4690.343351930733, 4475.351074094255, 4583.060472603109, 4669.066186762644, 4669.439667239936, 4679.8362057328, 4537.7277363358135, 4639.350490931272, 4647.070585882837, 4649.558291962258, 4659.976700116495, 4629.282461218517, 4632.467266264389, 4643.489216186217, 4644.512326205733, 4656.930146047812, 4092.202020605983, 4093.0585965228165, 4098.754074755494, 4099.344104943195, 4106.425100485934, 5628.183913332585, 5742.971285143548, 5743.597948143632, 5752.252207213078, 5754.510866576346, 5440.510354068113, 5441.129419292934, 5443.1563107717975, 5448.809291674812, 5453.754581043823, 6437.678116094224, 6442.774502892376, 6444.0, 6444.581102228341, 6449.419552240321, 4551.317302404633, 4566.041131362418, 4566.10960862632, 4568.063546972862, 4575.2450845610765, 6567.0, 6699.430548403391, 6699.698513566853, 6700.93299067008, 6706.597604143767, 4679.953200467951, 4680.906381872343, 4684.4378674559275, 4685.344051832746, 4688.226442636995, 4744.0, 4745.1221524018165, 4745.335653008581, 4746.881327966759, 4749.168895443284, 5242.423333433319, 5417.674939503642, 5425.755840987165, 5425.7829686812875, 5439.48324909133, 6485.54601177913, 6506.4469520091325, 6517.9348206517325, 6527.738890444329, 6528.379803918715, 1618.741001439764, 1634.1747417554059, 1634.689409012284, 1635.9938637737705, 1639.0984958272902, 1051.8527406163143, 1055.8627378440908, 1070.03162138265, 1073.1790180511914, 1086.869575650927, 6247.031710084944, 6261.373862613702, 6262.436380725757, 6266.624002559861, 6269.843253918689, 6566.967165164144, 6601.405873471411, 6601.669916504252, 6611.33886611335, 6619.635920024445, 6256.562040657202, 6322.683865806784, 6337.841553961188, 6364.299927008025, 6431.967840160863, 3605.080704420356, 3634.436662317345, 3637.199816040467, 3643.3441980443777, 3645.453182022718, 6763.154605674291, 6891.552049116892, 6908.792736217922, 6917.0, 6921.688524016431, 6898.0, 6908.723651054002, 6911.067005954233, 6914.170299564084, 6919.273476285019, 6917.688704008332, 6917.965410172917, 6921.515493915478, 6922.134733158356, 6925.134358205224, 6622.304658010839, 6674.399304062657, 6748.460123190232, 6750.2237242715755, 6758.155230596176, 6631.834204144935, 6634.535582509178, 6638.933610663831, 6639.402453779183, 6640.568363056386, 6907.0, 6919.20429150634, 6924.723011079501, 6924.753544361862, 6934.791956241321, 4568.383268258743, 4660.39414876064, 4660.4174478190225, 4667.346571480063, 4669.7431641260055, 6717.496187785838, 6727.865442691216, 6730.293319201609, 6738.157730283715, 6739.696713647995, 6565.704543295658, 6565.80302590923, 6566.737330506726, 6566.770163044462, 6568.507361947969, 6440.8389790255615, 6550.672466376567, 6559.4752419807255, 6562.704678289584, 6565.277819439953, 6439.3238709934785, 6450.516211284079, 6455.709493072729, 6458.870822583616, 6459.709313080829, 6561.081448597296, 6569.047488114292, 6571.967140164268, 6573.769918052845, 6579.342065793382, 6298.433141017331, 6415.550911436155, 6423.582467139621, 6427.6786160690845, 6431.0], "unit": "ms", "name": "prototype.html", "value": 4904.560462713958}, {"lowerIsBetter": false, "replicates": [804.0873608454402, 850.7490290364342, 919.8574220995744, 921.3642586615239, 951.9813799234819, 13982.021258511908, 16037.198140093007, 16378.7543186852, 16385.262663180154, 16450.424235151786, 15363.3086511107, 15421.92289038556, 15435.45975890844, 15463.458778942739, 15470.61323466914, 730.9122905251377, 740.2264633458022, 872.4067634008903, 876.7851876290301, 881.5107615273521, 892.7589550821274, 893.4014210478962, 900.4507250577143, 900.9819803603924, 901.269971323227, 934.8317302885478, 942.5004747483845, 942.853857652065, 943.4197968249512, 943.6838659049226, 771.6990373754212, 783.4515838912783, 914.7987442762615, 927.0775578299567, 928.0487500312234, 693.715576613591, 694.1150033707064, 694.3577191098213, 694.3577191098265, 694.4097517110466, 13911.582652520441, 14003.369848356746, 14011.579652610439, 14017.0, 14033.438662453489, 12057.336846473441, 12282.877171228263, 13056.804147937788, 13281.734365312735, 13541.322933853267, 10760.838587421194, 10775.0, 10856.45717714119, 10860.45697715119, 11716.179867409284, 3883.165119499321, 3896.8636097736726, 3970.086880017583, 3976.5029371328583, 3991.7006224533275, 50381.24428133581, 50658.0, 51301.0, 51438.99999999963, 52848.20727689088, 10444.373337599769, 10626.681199564026, 10764.730881727863, 10814.080803131821, 10829.0, 18512.537186570175, 18518.0, 18554.907225464056, 18576.535586610444, 18596.907015464836, 7556.1310449297935, 7579.204183560757, 7733.806654833562, 7824.33493153077, 7895.407844411578, 4620.3762492063815, 4742.288656701522, 4818.638602104857, 4871.878203044881, 4899.1426500362295, 3272.8363581820813, 3312.354090952241, 3317.950230746541, 3318.7179089777155, 3319.8340082996237, 576.8788554403623, 597.602594274811, 602.7408214467821, 605.3159929279885, 605.8697380063307, 679.1341040173818, 684.9589024658437, 700.8668353013013, 723.6779633063342, 740.8481261341412, 8457.661693532313, 9840.803183936425, 9909.058639429242, 9954.452505112144, 10047.899521004696, 504.90154419888506, 513.3044724398461, 534.1293691283183, 654.0842820051965, 677.4851113154039, 3924.4309575111447, 3946.210757848442, 4061.1065565575527, 4074.307367747431, 4284.143171365739, 57937.0, 58651.70674146602, 60705.0, 61676.07485887626, 61960.69019654873, 50799.746001269756, 52080.73959630178, 52395.214071788956, 52530.21204681933, 54373.184402234, 47479.0, 48835.267470987965, 49580.0, 49892.50107498879, 50284.999999999265, 8844.159804818531, 9270.212031977357, 9354.71935841926, 9435.952820235854, 9572.473513956798, 34952.475712864834, 35986.0, 36485.81757091304, 36723.63276367202, 36797.81601091977, 43373.78313108414, 43386.56613433825, 45562.31656525221, 46947.295790563854, 47890.52109478861, 11263.66209013731, 11486.827697584376, 11587.304761714324, 11792.587259446222, 11813.76372472563, 13063.869361306266, 13105.2136871788, 13135.868641313848, 13160.473581057033, 13291.136076155019, 11904.226225295328, 11973.10201734856, 12278.447469864057, 12497.312647804458, 12565.0, 11211.159163062639, 11341.26281791681, 11383.658490245305, 11848.28910265387, 11940.522379105087, 10379.377237365783, 10836.837447438453, 11062.668119956414, 11082.61210857616, 11195.10439164881, 10288.588456461657, 10905.127589792955, 10936.179786516203, 10938.45307734626, 11112.888711128822, 9938.801223975624, 10101.14140298083, 10166.0, 10223.693289201336, 10284.845727314247], "unit": "ms", "name": "yui.html", "value": 6689.638039709477}], "name": "dromaeo_css", "value": 7201.859384490547}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 104.0, 109.0, 109.0, 110.0, 98.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.6}, {"lowerIsBetter": true, "replicates": [146.0, 131.0, 146.0, 170.0, 130.0, 143.0, 153.0, 126.0, 166.0, 129.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.0}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 161.0, 158.0, 160.0, 160.0, 159.0, 160.0, 167.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [78.0, 71.0, 71.0, 156.0, 71.0, 72.0, 72.0, 70.0, 158.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 89.0}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 139.0, 93.0, 92.0, 92.0, 138.0, 92.0, 93.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.9}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [148.0, 145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [54.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0, 56.0, 55.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [62.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 63.0, 64.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.5}, {"lowerIsBetter": true, "replicates": [80.0, 79.0, 82.0, 80.0, 81.0, 80.0, 80.0, 81.0, 81.0, 81.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.5}, {"lowerIsBetter": true, "replicates": [116.0, 118.0, 116.0, 129.0, 112.0, 111.0, 117.0, 126.0, 124.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.9}, {"lowerIsBetter": true, "replicates": [135.0, 144.0, 143.0, 146.0, 143.0, 135.0, 132.0, 153.0, 143.0, 141.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.5}, {"lowerIsBetter": true, "replicates": [63.0, 66.0, 67.0, 66.0, 67.0, 64.0, 65.0, 62.0, 67.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1481.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.20857863751053], "unit": "score", "name": "Richards", "value": 209.87038999999996}, {"lowerIsBetter": false, "replicates": [173.94329448599757], "unit": "score", "name": "DeltaBlue", "value": 380.11238199999985}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [1002.0040080160321], "unit": "score", "name": "RayTrace", "value": 738.509022}, {"lowerIsBetter": false, "replicates": [558.3472920156337], "unit": "score", "name": "Earley", "value": 1193.6352329999995}, {"lowerIsBetter": false, "replicates": [11109.89010989011], "unit": "score", "name": "Boyer", "value": 59.98826211671617}, {"lowerIsBetter": false, "replicates": [23928.571428571428], "unit": "score", "name": "RegExp", "value": 38.071014925373106}, {"lowerIsBetter": false, "replicates": [516.2622612287042], "unit": "score", "name": "Splay", "value": 157.84806699999996}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 22882.93111144421}]} 09:50:46 INFO - RETURN: dromaeo_css: 7201.86 09:50:46 INFO - RETURN: kraken: 1481.80 09:50:46 INFO - RETURN: v8_7: 22882.93 09:50:46 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "22882.93"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1481.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7201.86"}}} 09:50:46 INFO - Return code: 0 09:50:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:50:46 INFO - # TBPL SUCCESS # 09:50:46 INFO - Running post-action listener: _resource_record_post_action 09:50:46 INFO - Running post-run listener: _resource_record_post_run 09:50:47 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 0; Write bytes: 505839616; Read time: 0; Write time: 478256 09:50:47 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 242196480; Read time: 0; Write time: 325820 09:50:47 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 0; Write bytes: 263643136; Read time: 0; Write time: 152436 09:50:47 INFO - Running post-run listener: _upload_blobber_files 09:50:47 INFO - Blob upload gear active. 09:50:47 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:50:47 INFO - Copying logs to upload dir... 09:50:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.583056 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-01-21 09:50:47.182683) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-21 09:50:47.186013) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010070 build_url: 'https://queue.taskcluster.net/v1/task/tLa9pncVT8qGSu8AsFpoUQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-21 09:50:47.238248) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:50:47.238628) ========= 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=652eb03db935cfc1c15ebda0000001ad-1453397387.945188-401449727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004544 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 09:50:47.291896) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 09:50:47.292641) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 09:50:47.293327) ========= ========= Total master_lag: 0.28 =========