builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1453568652.53 results: success (0) buildid: 20160123064329 builduid: 00521244bc5647c5ad2f6312d4c0867c revision: a4743600b7507c8e09c9e324aaf42c5928951249 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.526179) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.526572) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.526904) ========= 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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006415 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.553898) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.554177) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.574364) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.574625) ========= 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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004657 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.628343) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.628612) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.628962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.629253) ========= 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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-23 09:04:12-- 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% 43.6M=0s 2016-01-23 09:04:13 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.820555) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.820900) ========= 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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017257 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.870842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:12.871150) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a4743600b7507c8e09c9e324aaf42c5928951249 --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 a4743600b7507c8e09c9e324aaf42c5928951249 --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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-23 09:04:13,227 truncating revision to first 12 chars 2016-01-23 09:04:13,227 Setting DEBUG logging. 2016-01-23 09:04:13,227 attempt 1/10 2016-01-23 09:04:13,227 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a4743600b750?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-23 09:04:13,482 unpacking tar archive at: mozilla-aurora-a4743600b750/testing/mozharness/ program finished with exit code 0 elapsedTime=0.523716 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:13.411091) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:13.411412) ========= echo a4743600b7507c8e09c9e324aaf42c5928951249 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'a4743600b7507c8e09c9e324aaf42c5928951249'] 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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False a4743600b7507c8e09c9e324aaf42c5928951249 program finished with exit code 0 elapsedTime=0.004630 script_repo_revision: 'a4743600b7507c8e09c9e324aaf42c5928951249' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:13.461812) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:13.462135) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-23 09:04:13.471986) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-01-23 09:04:13.472264) ========= /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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:04:13 INFO - MultiFileLogger online at 20160123 09:04:13 in /builds/slave/test-pgo 09:04:13 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:04:13 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:04:13 INFO - {'append_to_log': False, 09:04:13 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:04:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:04:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:04:13 INFO - 'branch': 'Mozilla-Aurora', 09:04:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:04:13 INFO - 'config_files': ('talos/linux_config.py',), 09:04:13 INFO - 'default_actions': ('clobber', 09:04:13 INFO - 'read-buildbot-config', 09:04:13 INFO - 'download-and-extract', 09:04:13 INFO - 'populate-webroot', 09:04:13 INFO - 'create-virtualenv', 09:04:13 INFO - 'install', 09:04:13 INFO - 'run-tests'), 09:04:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:04:13 INFO - 'download_minidump_stackwalk': True, 09:04:13 INFO - 'download_symbols': 'ondemand', 09:04:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:04:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:04:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:04:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:04:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:04:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:04:13 INFO - 'installer_path': 'installer.exe', 09:04:13 INFO - 'log_level': 'info', 09:04:13 INFO - 'log_name': 'talos', 09:04:13 INFO - 'log_to_console': True, 09:04:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:04:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:04:13 INFO - 'opt_config_files': (), 09:04:13 INFO - 'pip_index': False, 09:04:13 INFO - 'sps_profile': False, 09:04:13 INFO - 'sps_profile_interval': 0, 09:04:13 INFO - 'suite': 'dromaeojs', 09:04:13 INFO - 'system_bits': '32', 09:04:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:04:13 INFO - 'title': 'talos-linux64-ix-059', 09:04:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:04:13 INFO - 'use_talos_json': True, 09:04:13 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:04:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:04:13 INFO - 'work_dir': 'build'} 09:04:13 INFO - ##### 09:04:13 INFO - ##### Running clobber step. 09:04:13 INFO - ##### 09:04:13 INFO - Running pre-action listener: _resource_record_pre_action 09:04:13 INFO - Running main action method: clobber 09:04:13 INFO - rmtree: /builds/slave/test-pgo/build 09:04:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:04:14 INFO - Running post-action listener: _resource_record_post_action 09:04:14 INFO - ##### 09:04:14 INFO - ##### Running read-buildbot-config step. 09:04:14 INFO - ##### 09:04:14 INFO - Running pre-action listener: _resource_record_pre_action 09:04:14 INFO - Running main action method: read_buildbot_config 09:04:14 INFO - Using buildbot properties: 09:04:14 INFO - { 09:04:14 INFO - "properties": { 09:04:14 INFO - "buildnumber": 110, 09:04:14 INFO - "product": "firefox", 09:04:14 INFO - "script_repo_revision": "production", 09:04:14 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:04:14 INFO - "repository": "", 09:04:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:04:14 INFO - "buildid": "20160123064329", 09:04:14 INFO - "slavename": "talos-linux64-ix-059", 09:04:14 INFO - "pgo_build": "True", 09:04:14 INFO - "basedir": "/builds/slave/test-pgo", 09:04:14 INFO - "project": "", 09:04:14 INFO - "platform": "ubuntu64_hw", 09:04:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:04:14 INFO - "slavebuilddir": "test", 09:04:14 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:04:14 INFO - "branch": "mozilla-aurora", 09:04:14 INFO - "repo_path": "releases/mozilla-aurora", 09:04:14 INFO - "stage_platform": "linux64-pgo", 09:04:14 INFO - "builduid": "00521244bc5647c5ad2f6312d4c0867c", 09:04:14 INFO - "revision": "a4743600b7507c8e09c9e324aaf42c5928951249" 09:04:14 INFO - }, 09:04:14 INFO - "sourcestamp": { 09:04:14 INFO - "repository": "", 09:04:14 INFO - "hasPatch": false, 09:04:14 INFO - "project": "", 09:04:14 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:04:14 INFO - "changes": [ 09:04:14 INFO - { 09:04:14 INFO - "category": null, 09:04:14 INFO - "files": [ 09:04:14 INFO - { 09:04:14 INFO - "url": null, 09:04:14 INFO - "name": "https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 09:04:14 INFO - } 09:04:14 INFO - ], 09:04:14 INFO - "repository": "", 09:04:14 INFO - "rev": "a4743600b7507c8e09c9e324aaf42c5928951249", 09:04:14 INFO - "who": "ryanvm@gmail.com", 09:04:14 INFO - "when": 1453568630, 09:04:14 INFO - "number": 7505447, 09:04:14 INFO - "comments": "Bug 1240559 - Ship NewTabURL.jsm in 45. r=mconley, a=sylvestre", 09:04:14 INFO - "project": "", 09:04:14 INFO - "at": "Sat 23 Jan 2016 09:03:50", 09:04:14 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 09:04:14 INFO - "revlink": "", 09:04:14 INFO - "properties": [ 09:04:14 INFO - [ 09:04:14 INFO - "buildid", 09:04:14 INFO - "20160123064329", 09:04:14 INFO - "Change" 09:04:14 INFO - ], 09:04:14 INFO - [ 09:04:14 INFO - "builduid", 09:04:14 INFO - "00521244bc5647c5ad2f6312d4c0867c", 09:04:14 INFO - "Change" 09:04:14 INFO - ], 09:04:14 INFO - [ 09:04:14 INFO - "pgo_build", 09:04:14 INFO - "True", 09:04:14 INFO - "Change" 09:04:14 INFO - ] 09:04:14 INFO - ], 09:04:14 INFO - "revision": "a4743600b7507c8e09c9e324aaf42c5928951249" 09:04:14 INFO - } 09:04:14 INFO - ], 09:04:14 INFO - "revision": "a4743600b7507c8e09c9e324aaf42c5928951249" 09:04:14 INFO - } 09:04:14 INFO - } 09:04:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 09:04:14 INFO - Running post-action listener: _resource_record_post_action 09:04:14 INFO - ##### 09:04:14 INFO - ##### Running download-and-extract step. 09:04:14 INFO - ##### 09:04:14 INFO - Running pre-action listener: _resource_record_pre_action 09:04:14 INFO - Running main action method: download_and_extract 09:04:14 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:04:14 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:04:14 INFO - https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:04:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/test_packages.json 09:04:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/test_packages.json 09:04:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:04:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:04:15 INFO - Downloaded 1302 bytes. 09:04:15 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:04:15 INFO - Using the following test package requirements: 09:04:15 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:04:15 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:04:15 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:04:15 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:04:15 INFO - u'jsshell-linux-x86_64.zip'], 09:04:15 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:04:15 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:04:15 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:04:15 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:04:15 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:04:15 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:04:15 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:04:15 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:04:15 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:04:15 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 09:04:15 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 09:04:15 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:04:15 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:04:15 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:04:15 INFO - https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:04:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:04:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:04:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:16 INFO - Downloaded 23159143 bytes. 09:04:16 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:04:16 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:04:16 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:04:17 INFO - Return code: 0 09:04:17 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:04:17 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:04:17 INFO - https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:04:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:04:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:04:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:21 INFO - Downloaded 23159143 bytes. 09:04:21 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:04:21 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 09:04:21 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:04:23 INFO - Return code: 0 09:04:23 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:04:23 INFO - https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:04:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:04:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:04:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:24 INFO - Downloaded 11132038 bytes. 09:04:24 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:04:24 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 09:04:24 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:04:24 INFO - Return code: 0 09:04:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:24 INFO - https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:04:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:04:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:04:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/kVETwNojQkCuPfXSpJCHmg/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:04:25 INFO - Downloaded 58032145 bytes. 09:04:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:04:25 INFO - mkdir: /builds/slave/test-pgo/properties 09:04:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:04:25 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:04:25 INFO - Contents: 09:04:25 INFO - build_url:https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 09:04:25 INFO - Running post-action listener: _resource_record_post_action 09:04:25 INFO - Running post-action listener: set_extra_try_arguments 09:04:25 INFO - ##### 09:04:25 INFO - ##### Running populate-webroot step. 09:04:25 INFO - ##### 09:04:25 INFO - Running pre-action listener: _resource_record_pre_action 09:04:25 INFO - Running main action method: populate_webroot 09:04:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:04:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:04:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:04:25 INFO - '--tppagecycles', 09:04:25 INFO - '7'], 09:04:25 INFO - 'tests': ['tsvgm']}}, 09:04:25 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:04:25 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:04:25 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:04:25 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:04:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'tests': ['damp', 'tps']}, 09:04:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['damp', 'tps']}, 09:04:25 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:04:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:04:25 INFO - 'other': {'tests': ['a11yr', 09:04:25 INFO - 'ts_paint', 09:04:25 INFO - 'tpaint', 09:04:25 INFO - 'sessionrestore', 09:04:25 INFO - 'sessionrestore_no_auto_restore']}, 09:04:25 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['a11yr', 09:04:25 INFO - 'ts_paint', 09:04:25 INFO - 'tpaint', 09:04:25 INFO - 'sessionrestore', 09:04:25 INFO - 'sessionrestore_no_auto_restore']}, 09:04:25 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['a11yr', 09:04:25 INFO - 'ts_paint', 09:04:25 INFO - 'tpaint', 09:04:25 INFO - 'sessionrestore', 09:04:25 INFO - 'sessionrestore_no_auto_restore']}, 09:04:25 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['a11yr', 09:04:25 INFO - 'ts_paint', 09:04:25 INFO - 'tpaint', 09:04:25 INFO - 'sessionrestore', 09:04:25 INFO - 'sessionrestore_no_auto_restore']}, 09:04:25 INFO - 'other_l64': {'tests': ['a11yr', 09:04:25 INFO - 'ts_paint', 09:04:25 INFO - 'tpaint', 09:04:25 INFO - 'sessionrestore', 09:04:25 INFO - 'sessionrestore_no_auto_restore']}, 09:04:25 INFO - 'other_nol64': {'tests': ['a11yr', 09:04:25 INFO - 'ts_paint', 09:04:25 INFO - 'tpaint', 09:04:25 INFO - 'sessionrestore', 09:04:25 INFO - 'sessionrestore_no_auto_restore']}, 09:04:25 INFO - 'svgr': {'tests': ['tsvgx', 09:04:25 INFO - 'tsvgr_opacity', 09:04:25 INFO - 'tart', 09:04:25 INFO - 'tscrollx', 09:04:25 INFO - 'cart']}, 09:04:25 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['tsvgx', 09:04:25 INFO - 'tsvgr_opacity', 09:04:25 INFO - 'tart', 09:04:25 INFO - 'tscrollx', 09:04:25 INFO - 'cart']}, 09:04:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'tests': ['tp5o']}, 09:04:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'talos_options': ['--e10s'], 09:04:25 INFO - 'tests': ['tp5o']}, 09:04:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'talos_options': ['--xperf_path', 09:04:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:25 INFO - 'tests': ['tp5n']}, 09:04:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:25 INFO - 'talos_options': ['--e10s', 09:04:25 INFO - '--xperf_path', 09:04:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:25 INFO - 'tests': ['tp5n']}}, 09:04:25 INFO - 'talos.zip': {'path': '', 09:04:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:04:25 INFO - Running post-action listener: _resource_record_post_action 09:04:25 INFO - ##### 09:04:25 INFO - ##### Running create-virtualenv step. 09:04:25 INFO - ##### 09:04:25 INFO - Running pre-action listener: _resource_record_pre_action 09:04:25 INFO - Running main action method: create_virtualenv 09:04:25 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:04:25 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:04:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:04:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:04:25 INFO - Using real prefix '/usr' 09:04:25 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:04:26 INFO - Installing distribute.............................................................................................................................................................................................done. 09:04:28 INFO - Installing pip.................done. 09:04:28 INFO - Return code: 0 09:04:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:04:28 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:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:28 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:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:28 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:28 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:04:28 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:04:28 INFO - Using env: {'DISPLAY': ':0', 09:04:28 INFO - 'HOME': '/home/cltbld', 09:04:28 INFO - 'LANG': 'en_US.UTF-8', 09:04:28 INFO - 'LANGUAGE': 'en_US:en', 09:04:28 INFO - 'LOGNAME': 'cltbld', 09:04:28 INFO - 'MAIL': '/var/mail/cltbld', 09:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:28 INFO - 'MOZ_NO_REMOTE': '1', 09:04:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:28 INFO - 'NO_EM_RESTART': '1', 09:04:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:28 INFO - 'SHELL': '/bin/bash', 09:04:28 INFO - 'SHLVL': '1', 09:04:28 INFO - 'TERM': 'linux', 09:04:28 INFO - 'TMOUT': '86400', 09:04:28 INFO - 'USER': 'cltbld', 09:04:28 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:28 INFO - '_': '/tools/buildbot/bin/python'} 09:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:28 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:04:28 INFO - Cleaning up... 09:04:28 INFO - Return code: 0 09:04:28 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:04:28 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:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:28 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:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:28 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:28 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:04:28 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:04:28 INFO - Using env: {'DISPLAY': ':0', 09:04:28 INFO - 'HOME': '/home/cltbld', 09:04:28 INFO - 'LANG': 'en_US.UTF-8', 09:04:28 INFO - 'LANGUAGE': 'en_US:en', 09:04:28 INFO - 'LOGNAME': 'cltbld', 09:04:28 INFO - 'MAIL': '/var/mail/cltbld', 09:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:28 INFO - 'MOZ_NO_REMOTE': '1', 09:04:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:28 INFO - 'NO_EM_RESTART': '1', 09:04:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:28 INFO - 'SHELL': '/bin/bash', 09:04:28 INFO - 'SHLVL': '1', 09:04:28 INFO - 'TERM': 'linux', 09:04:28 INFO - 'TMOUT': '86400', 09:04:28 INFO - 'USER': 'cltbld', 09:04:28 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:28 INFO - '_': '/tools/buildbot/bin/python'} 09:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:28 INFO - Downloading/unpacking psutil>=0.7.1 09:04:28 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:04:28 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:04:28 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:04:28 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:04:28 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:04:28 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:04:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:04:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:30 INFO - Installing collected packages: psutil 09:04:30 INFO - Running setup.py install for psutil 09:04:30 INFO - building 'psutil._psutil_linux' extension 09:04:30 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:04:30 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:04:30 INFO - building 'psutil._psutil_posix' extension 09:04:30 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:04:30 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:04:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:31 INFO - Successfully installed psutil 09:04:31 INFO - Cleaning up... 09:04:31 INFO - Return code: 0 09:04:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:31 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:31 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:04:31 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:04:31 INFO - Using env: {'DISPLAY': ':0', 09:04:31 INFO - 'HOME': '/home/cltbld', 09:04:31 INFO - 'LANG': 'en_US.UTF-8', 09:04:31 INFO - 'LANGUAGE': 'en_US:en', 09:04:31 INFO - 'LOGNAME': 'cltbld', 09:04:31 INFO - 'MAIL': '/var/mail/cltbld', 09:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:31 INFO - 'MOZ_NO_REMOTE': '1', 09:04:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:31 INFO - 'NO_EM_RESTART': '1', 09:04:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:31 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:31 INFO - 'SHELL': '/bin/bash', 09:04:31 INFO - 'SHLVL': '1', 09:04:31 INFO - 'TERM': 'linux', 09:04:31 INFO - 'TMOUT': '86400', 09:04:31 INFO - 'USER': 'cltbld', 09:04:31 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:31 INFO - '_': '/tools/buildbot/bin/python'} 09:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:04:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:04:33 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:04:33 INFO - Installing collected packages: mozsystemmonitor 09:04:33 INFO - Running setup.py install for mozsystemmonitor 09:04:33 INFO - Successfully installed mozsystemmonitor 09:04:33 INFO - Cleaning up... 09:04:33 INFO - Return code: 0 09:04:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:04:33 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:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:33 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:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:33 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:33 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:04:33 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:04:33 INFO - Using env: {'DISPLAY': ':0', 09:04:33 INFO - 'HOME': '/home/cltbld', 09:04:33 INFO - 'LANG': 'en_US.UTF-8', 09:04:33 INFO - 'LANGUAGE': 'en_US:en', 09:04:33 INFO - 'LOGNAME': 'cltbld', 09:04:33 INFO - 'MAIL': '/var/mail/cltbld', 09:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:33 INFO - 'MOZ_NO_REMOTE': '1', 09:04:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:33 INFO - 'NO_EM_RESTART': '1', 09:04:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:33 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:33 INFO - 'SHELL': '/bin/bash', 09:04:33 INFO - 'SHLVL': '1', 09:04:33 INFO - 'TERM': 'linux', 09:04:33 INFO - 'TMOUT': '86400', 09:04:33 INFO - 'USER': 'cltbld', 09:04:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:33 INFO - '_': '/tools/buildbot/bin/python'} 09:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:35 INFO - Downloading blobuploader-1.2.4.tar.gz 09:04:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:04:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:04:35 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:04:35 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:04:35 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:04:35 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:04:35 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:04:35 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:04:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:04:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:04:35 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:04:35 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:04:35 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:04:35 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:04:35 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:04:35 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:04:35 INFO - Downloading docopt-0.6.1.tar.gz 09:04:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:04:35 INFO - Installing collected packages: blobuploader, requests, docopt 09:04:35 INFO - Running setup.py install for blobuploader 09:04:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:04:36 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:04:36 INFO - Running setup.py install for requests 09:04:36 INFO - Running setup.py install for docopt 09:04:36 INFO - Successfully installed blobuploader requests docopt 09:04:36 INFO - Cleaning up... 09:04:36 INFO - Return code: 0 09:04:36 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:04: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:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04: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:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:36 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:36 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:04:36 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:04:36 INFO - Using env: {'DISPLAY': ':0', 09:04:36 INFO - 'HOME': '/home/cltbld', 09:04:36 INFO - 'LANG': 'en_US.UTF-8', 09:04:36 INFO - 'LANGUAGE': 'en_US:en', 09:04:36 INFO - 'LOGNAME': 'cltbld', 09:04:36 INFO - 'MAIL': '/var/mail/cltbld', 09:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:36 INFO - 'MOZ_NO_REMOTE': '1', 09:04:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:36 INFO - 'NO_EM_RESTART': '1', 09:04:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:36 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:36 INFO - 'SHELL': '/bin/bash', 09:04:36 INFO - 'SHLVL': '1', 09:04:36 INFO - 'TERM': 'linux', 09:04:36 INFO - 'TMOUT': '86400', 09:04:36 INFO - 'USER': 'cltbld', 09:04:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:36 INFO - '_': '/tools/buildbot/bin/python'} 09:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:04:36 INFO - Running setup.py (path:/tmp/pip-KRRzl5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:04:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:04:36 INFO - Running setup.py (path:/tmp/pip-nEnOay-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:04:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:04:36 INFO - Running setup.py (path:/tmp/pip-HOaInT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:04:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:04:36 INFO - Running setup.py (path:/tmp/pip-7gtJkC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:04:37 INFO - Running setup.py (path:/tmp/pip-Zp_7QM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:04:37 INFO - Running setup.py (path:/tmp/pip-mIP7jq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:04:37 INFO - Running setup.py (path:/tmp/pip-UZKHDK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:04:37 INFO - Running setup.py (path:/tmp/pip-1QVX3X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:04:37 INFO - Running setup.py (path:/tmp/pip-WFaBvl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:04:37 INFO - Running setup.py (path:/tmp/pip-MHtTOW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:04:37 INFO - Running setup.py (path:/tmp/pip-reAFtk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:04:37 INFO - Running setup.py (path:/tmp/pip-4BXaBt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:04:37 INFO - Running setup.py (path:/tmp/pip-kJgBk7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:04:37 INFO - Running setup.py (path:/tmp/pip-P3u6OL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:04:37 INFO - Running setup.py (path:/tmp/pip-G7PWJ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:04:37 INFO - Running setup.py (path:/tmp/pip-_pKj3D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:04:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:04:37 INFO - Running setup.py (path:/tmp/pip-OeanH_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:04:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:04:37 INFO - Running setup.py install for manifestparser 09:04:38 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Running setup.py install for mozcrash 09:04:38 INFO - Running setup.py install for mozdebug 09:04:38 INFO - Running setup.py install for mozdevice 09:04:38 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Running setup.py install for mozfile 09:04:38 INFO - Running setup.py install for mozhttpd 09:04:38 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Running setup.py install for mozinfo 09:04:38 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Running setup.py install for mozInstall 09:04:38 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:04:38 INFO - Running setup.py install for mozleak 09:04:38 INFO - Running setup.py install for mozlog 09:04:38 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:04:39 INFO - Running setup.py install for moznetwork 09:04:39 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:04:39 INFO - Running setup.py install for mozprocess 09:04:39 INFO - Running setup.py install for mozprofile 09:04:39 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:04:39 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:04:39 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:04:39 INFO - Running setup.py install for mozrunner 09:04:39 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:04:39 INFO - Running setup.py install for mozscreenshot 09:04:39 INFO - Running setup.py install for moztest 09:04:39 INFO - Running setup.py install for mozversion 09:04:39 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:04:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:04:39 INFO - Cleaning up... 09:04:39 INFO - Return code: 0 09:04:39 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:04:39 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:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:39 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:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:39 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:39 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:04:39 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:04:39 INFO - Using env: {'DISPLAY': ':0', 09:04:39 INFO - 'HOME': '/home/cltbld', 09:04:39 INFO - 'LANG': 'en_US.UTF-8', 09:04:39 INFO - 'LANGUAGE': 'en_US:en', 09:04:39 INFO - 'LOGNAME': 'cltbld', 09:04:39 INFO - 'MAIL': '/var/mail/cltbld', 09:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:39 INFO - 'MOZ_NO_REMOTE': '1', 09:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:39 INFO - 'NO_EM_RESTART': '1', 09:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:39 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:39 INFO - 'SHELL': '/bin/bash', 09:04:39 INFO - 'SHLVL': '1', 09:04:39 INFO - 'TERM': 'linux', 09:04:39 INFO - 'TMOUT': '86400', 09:04:39 INFO - 'USER': 'cltbld', 09:04:39 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:39 INFO - '_': '/tools/buildbot/bin/python'} 09:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:04:39 INFO - Running setup.py (path:/tmp/pip-fNYgJ_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:04:40 INFO - Running setup.py (path:/tmp/pip-gamxtn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:04:40 INFO - Running setup.py (path:/tmp/pip-7BT3UT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:04:40 INFO - Running setup.py (path:/tmp/pip-dDFBi6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:04:40 INFO - Running setup.py (path:/tmp/pip-SdPwer-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:04:40 INFO - Running setup.py (path:/tmp/pip-WGg_ln-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:04:40 INFO - Running setup.py (path:/tmp/pip-768jOi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:04:40 INFO - Running setup.py (path:/tmp/pip-uSRG1l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:04:40 INFO - Running setup.py (path:/tmp/pip-eqya11-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:04:40 INFO - Running setup.py (path:/tmp/pip-KGTTMd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:04:40 INFO - Running setup.py (path:/tmp/pip-KpMx0z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:04:40 INFO - Running setup.py (path:/tmp/pip-VgxLgf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:04:40 INFO - Running setup.py (path:/tmp/pip-Qe9FD7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:04:40 INFO - Running setup.py (path:/tmp/pip-EvBT3a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:04:40 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:04:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:04:40 INFO - Running setup.py (path:/tmp/pip-PUcjKA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:04:41 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:04:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:04:41 INFO - Running setup.py (path:/tmp/pip-g3r9uh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:04:41 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:04:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:04:41 INFO - Running setup.py (path:/tmp/pip-D6a4KO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:04:41 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:04:41 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:04:41 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:04:41 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:04:41 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:04:41 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:04:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:04:41 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:04:41 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:04:41 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:04:41 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:04:41 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:04:41 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:04:42 INFO - Downloading blessings-1.6.tar.gz 09:04:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:04:43 INFO - Installing collected packages: blessings 09:04:43 INFO - Running setup.py install for blessings 09:04:43 INFO - Successfully installed blessings 09:04:43 INFO - Cleaning up... 09:04:43 INFO - Return code: 0 09:04:43 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:04:43 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:04:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:04:43 INFO - Reading from file tmpfile_stdout 09:04:43 INFO - Current package versions: 09:04:43 INFO - argparse == 1.2.1 09:04:43 INFO - blessings == 1.6 09:04:43 INFO - blobuploader == 1.2.4 09:04:43 INFO - docopt == 0.6.1 09:04:43 INFO - manifestparser == 1.1 09:04:43 INFO - mozInstall == 1.12 09:04:43 INFO - mozcrash == 0.16 09:04:43 INFO - mozdebug == 0.1 09:04:43 INFO - mozdevice == 0.47 09:04:43 INFO - mozfile == 1.2 09:04:43 INFO - mozhttpd == 0.7 09:04:43 INFO - mozinfo == 0.9 09:04:43 INFO - mozleak == 0.1 09:04:43 INFO - mozlog == 3.1 09:04:43 INFO - moznetwork == 0.27 09:04:43 INFO - mozprocess == 0.22 09:04:43 INFO - mozprofile == 0.27 09:04:43 INFO - mozrunner == 6.11 09:04:43 INFO - mozscreenshot == 0.1 09:04:43 INFO - mozsystemmonitor == 0.0 09:04:43 INFO - moztest == 0.7 09:04:43 INFO - mozversion == 1.4 09:04:43 INFO - psutil == 3.1.1 09:04:43 INFO - requests == 1.2.3 09:04:43 INFO - wsgiref == 0.1.2 09:04:43 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:04:43 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:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:43 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:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:43 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:43 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:04:43 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:04:43 INFO - Using env: {'DISPLAY': ':0', 09:04:43 INFO - 'HOME': '/home/cltbld', 09:04:43 INFO - 'LANG': 'en_US.UTF-8', 09:04:43 INFO - 'LANGUAGE': 'en_US:en', 09:04:43 INFO - 'LOGNAME': 'cltbld', 09:04:43 INFO - 'MAIL': '/var/mail/cltbld', 09:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:43 INFO - 'MOZ_NO_REMOTE': '1', 09:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:43 INFO - 'NO_EM_RESTART': '1', 09:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:43 INFO - 'SHELL': '/bin/bash', 09:04:43 INFO - 'SHLVL': '1', 09:04:43 INFO - 'TERM': 'linux', 09:04:43 INFO - 'TMOUT': '86400', 09:04:43 INFO - 'USER': 'cltbld', 09:04:43 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:43 INFO - '_': '/tools/buildbot/bin/python'} 09:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 INFO - Cleaning up... 09:04:43 INFO - Return code: 0 09:04:43 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:04:43 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:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:43 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:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:43 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:43 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:04:43 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:04:43 INFO - Using env: {'DISPLAY': ':0', 09:04:43 INFO - 'HOME': '/home/cltbld', 09:04:43 INFO - 'LANG': 'en_US.UTF-8', 09:04:43 INFO - 'LANGUAGE': 'en_US:en', 09:04:43 INFO - 'LOGNAME': 'cltbld', 09:04:43 INFO - 'MAIL': '/var/mail/cltbld', 09:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:43 INFO - 'MOZ_NO_REMOTE': '1', 09:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:43 INFO - 'NO_EM_RESTART': '1', 09:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:43 INFO - 'SHELL': '/bin/bash', 09:04:43 INFO - 'SHLVL': '1', 09:04:43 INFO - 'TERM': 'linux', 09:04:43 INFO - 'TMOUT': '86400', 09:04:43 INFO - 'USER': 'cltbld', 09:04:43 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:43 INFO - '_': '/tools/buildbot/bin/python'} 09:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:43 INFO - Downloading/unpacking jsonschema 09:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:43 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:04:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:04:45 INFO - Downloading/unpacking functools32 (from jsonschema) 09:04:45 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:04:45 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:04:45 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:04:45 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:04:45 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:04:45 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:04:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:04:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:04:45 INFO - warning: no files found matching '*.txt' 09:04:45 INFO - no previously-included directories found matching 'build' 09:04:45 INFO - no previously-included directories found matching 'dist' 09:04:45 INFO - no previously-included directories found matching '.git*' 09:04:45 INFO - Installing collected packages: jsonschema, functools32 09:04:45 INFO - Running setup.py install for functools32 09:04:45 INFO - warning: no files found matching '*.txt' 09:04:45 INFO - no previously-included directories found matching 'build' 09:04:45 INFO - no previously-included directories found matching 'dist' 09:04:45 INFO - no previously-included directories found matching '.git*' 09:04:46 INFO - Successfully installed jsonschema functools32 09:04:46 INFO - Cleaning up... 09:04:46 INFO - Return code: 0 09:04:46 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:04:46 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:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:46 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:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:46 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 0x7f3f02258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e30600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', '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:04:46 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:04:46 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:04:46 INFO - Using env: {'DISPLAY': ':0', 09:04:46 INFO - 'HOME': '/home/cltbld', 09:04:46 INFO - 'LANG': 'en_US.UTF-8', 09:04:46 INFO - 'LANGUAGE': 'en_US:en', 09:04:46 INFO - 'LOGNAME': 'cltbld', 09:04:46 INFO - 'MAIL': '/var/mail/cltbld', 09:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:46 INFO - 'MOZ_NO_REMOTE': '1', 09:04:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:46 INFO - 'NO_EM_RESTART': '1', 09:04:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:04:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:04:46 INFO - 'SHELL': '/bin/bash', 09:04:46 INFO - 'SHLVL': '1', 09:04:46 INFO - 'TERM': 'linux', 09:04:46 INFO - 'TMOUT': '86400', 09:04:46 INFO - 'USER': 'cltbld', 09:04:46 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:46 INFO - '_': '/tools/buildbot/bin/python'} 09:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:46 INFO - Downloading/unpacking flake8 09:04:46 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:04:46 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:04:46 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:04:46 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:04:46 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:04:46 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:04:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:04:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:04:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:04:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:04:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:04:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:04:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:04:48 INFO - no previously-included directories found matching 'docs/_build' 09:04:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:04:48 INFO - Running setup.py install for pep8 09:04:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:04:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:04:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:04:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:04:48 INFO - no previously-included directories found matching 'docs/_build' 09:04:48 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:04:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:04:48 INFO - Cleaning up... 09:04:48 INFO - Return code: 0 09:04:48 INFO - Running post-action listener: _resource_record_post_action 09:04:48 INFO - Running post-action listener: _start_resource_monitoring 09:04:48 INFO - Starting resource monitoring. 09:04:48 INFO - ##### 09:04:48 INFO - ##### Running install step. 09:04:48 INFO - ##### 09:04:48 INFO - Running pre-action listener: _resource_record_pre_action 09:04:48 INFO - Running main action method: install 09:04:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:04:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:04:49 INFO - Reading from file tmpfile_stdout 09:04:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:04:49 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:04:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:04:49 INFO - Reading from file tmpfile_stdout 09:04:49 INFO - Output received: 09:04:49 INFO - Usage: mozinstall [options] installer 09:04:49 INFO - Options: 09:04:49 INFO - -h, --help show this help message and exit 09:04:49 INFO - -d DEST, --destination=DEST 09:04:49 INFO - Directory to install application into. [default: 09:04:49 INFO - "/builds/slave/test-pgo"] 09:04:49 INFO - --app=APP Application being installed. [default: firefox] 09:04:49 INFO - mkdir: /builds/slave/test-pgo/build/application 09:04:49 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:04:49 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:05:03 INFO - Reading from file tmpfile_stdout 09:05:03 INFO - Output received: 09:05:03 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:05:03 INFO - Running post-action listener: _resource_record_post_action 09:05:03 INFO - ##### 09:05:03 INFO - ##### Running run-tests step. 09:05:03 INFO - ##### 09:05:03 INFO - Running pre-action listener: _resource_record_pre_action 09:05:03 INFO - Running main action method: run_tests 09:05:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:05:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:05:03 INFO - Python 2.7.3 09:05:03 INFO - Return code: 0 09:05:03 INFO - grabbing minidump binary from tooltool 09:05: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:05:03 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 0x1ebf350>, '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 0x1ec0250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec06e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:05:03 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:05:03 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:05:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:05:03 INFO - Return code: 0 09:05:03 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:05:03 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:05:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:05:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:05:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:05:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:05:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:05:04 INFO - Return code: 0 09:05:04 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/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:05:04 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/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost 09:05:04 INFO - Using env: {'DISPLAY': ':0', 09:05:04 INFO - 'HOME': '/home/cltbld', 09:05:04 INFO - 'LANG': 'en_US.UTF-8', 09:05:04 INFO - 'LANGUAGE': 'en_US:en', 09:05:04 INFO - 'LOGNAME': 'cltbld', 09:05:04 INFO - 'MAIL': '/var/mail/cltbld', 09:05:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:05:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:04 INFO - 'MOZ_NO_REMOTE': '1', 09:05:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:04 INFO - 'NO_EM_RESTART': '1', 09:05:04 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:05:04 INFO - 'PWD': '/builds/slave/test-pgo', 09:05:04 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:05:04 INFO - 'SHELL': '/bin/bash', 09:05:04 INFO - 'SHLVL': '1', 09:05:04 INFO - 'TERM': 'linux', 09:05:04 INFO - 'TMOUT': '86400', 09:05:04 INFO - 'USER': 'cltbld', 09:05:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524', 09:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:04 INFO - '_': '/tools/buildbot/bin/python'} 09:05:04 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/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost'] with output_timeout 3600 09:05:05 INFO - mozversion INFO | application_buildid: 20160123064329 09:05:05 INFO - mozversion INFO | application_changeset: a4743600b7507c8e09c9e324aaf42c5928951249 09:05:05 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 09:05:05 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:05:05 INFO - mozversion INFO | application_name: Firefox 09:05:05 INFO - mozversion INFO | application_remotingname: firefox-dev 09:05:05 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:05:05 INFO - mozversion INFO | application_vendor: Mozilla 09:05:05 INFO - mozversion INFO | application_version: 45.0a2 09:05:05 INFO - mozversion INFO | platform_buildid: 20160123064329 09:05:05 INFO - mozversion INFO | platform_changeset: a4743600b7507c8e09c9e324aaf42c5928951249 09:05:05 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:05:05 INFO - mozversion INFO | platform_version: 45.0a2 09:05:05 INFO - 2016-01-23 09:05:05,199 DEBUG : using testdate: 1453568705 09:05:05 INFO - 2016-01-23 09:05:05,199 DEBUG : actual date: 1453568705 09:05:05 INFO - 2016-01-23 09:05:05,248 INFO : starting webserver on 'localhost:43750' 09:05:05 INFO - 2016-01-23 09:05:05,249 INFO : Starting test suite talos-linux64-ix-059 09:05:05 INFO - 2016-01-23 09:05:05,250 INFO : Starting test dromaeo_css 09:05:05 INFO - 2016-01-23 09:05:05,250 DEBUG : operating with platform_type : linux_ 09:05:05 INFO - 2016-01-23 09:05:05,250 INFO : Initialising browser for dromaeo_css test... 09:05:05 INFO - 2016-01-23 09:05:05,264 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCKLOzi/profile http://localhost:43750/getInfo.html 09:05:09 INFO - 2016-01-23 09:05:09,181 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:05:09 INFO - 2016-01-23 09:05:09,181 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:05:09 INFO - 2016-01-23 09:05:09,181 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:05:09 INFO - 2016-01-23 09:05:09,181 DEBUG : BROWSER_OUTPUT: __metrics 09:05:09 INFO - 2016-01-23 09:05:09,318 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:05:10 INFO - 2016-01-23 09:05:10,134 INFO : Browser initialized. 09:05:10 INFO - 2016-01-23 09:05:10,135 INFO : Running cycle 1/1 for dromaeo_css test... 09:05:10 INFO - 2016-01-23 09:05:10,135 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCKLOzi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:05:21 INFO - 2016-01-23 09:05:21,902 DEBUG : BROWSER_OUTPUT: RSS: Main: 137408512 09:05:21 INFO - 2016-01-23 09:05:21,903 DEBUG : BROWSER_OUTPUT: 09:06:13 INFO - 2016-01-23 09:06:13,652 DEBUG : BROWSER_OUTPUT: 1453568773650 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:08:20 INFO - 2016-01-23 09:08:20,753 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43750/tests/dromaeo/cssquery-ext.html) 09:08:21 INFO - 2016-01-23 09:08:21,890 DEBUG : BROWSER_OUTPUT: RSS: Main: 210616320 09:08:21 INFO - 2016-01-23 09:08:21,890 DEBUG : BROWSER_OUTPUT: 09:11:24 INFO - 2016-01-23 09:11:24,131 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/dromaeo/cssquery-ext.html (next: http://localhost:43750/tests/dromaeo/cssquery-jquery.html) 09:11:24 INFO - 2016-01-23 09:11:24,693 DEBUG : BROWSER_OUTPUT: RSS: Main: 214999040 09:11:24 INFO - 2016-01-23 09:11:24,693 DEBUG : BROWSER_OUTPUT: 09:14:26 INFO - 2016-01-23 09:14:26,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43750/tests/dromaeo/cssquery-mootools.html) 09:14:27 INFO - 2016-01-23 09:14:27,114 DEBUG : BROWSER_OUTPUT: RSS: Main: 218054656 09:14:27 INFO - 2016-01-23 09:14:27,114 DEBUG : BROWSER_OUTPUT: 09:17:28 INFO - 2016-01-23 09:17:28,863 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43750/tests/dromaeo/cssquery-prototype.html) 09:17:29 INFO - 2016-01-23 09:17:29,436 DEBUG : BROWSER_OUTPUT: RSS: Main: 200376320 09:17:29 INFO - 2016-01-23 09:17:29,436 DEBUG : BROWSER_OUTPUT: 09:20:31 INFO - 2016-01-23 09:20:31,855 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43750/tests/dromaeo/cssquery-yui.html) 09:20:32 INFO - 2016-01-23 09:20:32,438 DEBUG : BROWSER_OUTPUT: RSS: Main: 205012992 09:20:32 INFO - 2016-01-23 09:20:32,438 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,584 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/dromaeo/cssquery-yui.html (next: http://localhost:43750/tests/dromaeo/cssquery-dojo.html) 09:23:34 INFO - 2016-01-23 09:23:34,835 DEBUG : BROWSER_OUTPUT: RSS: Main: 216199168 09:23:34 INFO - 2016-01-23 09:23:34,835 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,836 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:23:34 INFO - 2016-01-23 09:23:34,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:23:34 INFO - 2016-01-23 09:23:34,836 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:23:34 INFO - 2016-01-23 09:23:34,837 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:23:34 INFO - 2016-01-23 09:23:34,838 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3224.596925384327;3267.542544043833;3298.257891974306;3300.026492184805;3317.1209629448194;5308.973455132728;5314.76083576239;5332.413434522205;5333.786648534064;5374.865628359288;5302.628815982883;5313.57491400688;5336.252924590561;5348.144296912495;5355.036093503168;4772.212584923501;4901.970586176907;4929.260610908356;4943.777530011158;4985.977874535712;4888.951110488885;4948.2330238812865;4960.23116416956;4966.081274964125;4975.875603109915;4775.379200703904;4840.394950631171;4914.385701787277;4923.9753801230945;4925.630577706668;4912.263160525914;4961.454240033593;4988.65079444439;5013.273075404082;5016.372953380827;4975.402951645826;4982;4989.800407983676;5007.24891266313;5020.824271150492;5051.318072060299;5163.019026385013;5257.290265814143;5277.261183434322;5292.994331077083;5730.971345143247;5841.386654401312;5842.094475356326;5845.444682755132;5861.120831875211;5835.79574714883;5839.0949402842625;5844.941550584483;5850.853728656775;5854.47309742121;5299.814506492255;5402.945970540323;5421.728913554385;5452.645578037414;5456.072467680504;5779.162021506942;5849.970750146307;5884.6469211847425;5891.882162356815;5924.496417804448;5649.237352957381;5652.3217213934595;5727.828165155052;5751.194832723422;5765.88468230633;5636.605437619327;5740.253767010262;5767.740451679684;5820.970895145497;5843.707814609337;5809.912851307234;5821.941780582225;5828;5836.416358364129;5840.153177789197;5793.275840519935;5798.507126894261;5801.7389217485315;5813.709314534256;5818.709064546756;4324.17840610283;4398.8020539075815;4414.1171765647;4491.124230774967;4510.120526497367;4046.8785936421955;4047.534533528682;4054.5134583850127;4056.675465962775;4089.7137200396282;5548.667079975215;5574.916376254359;5576.1356989666665;5578.30271216098;5602.831915042555;5452.972735136299;5611.9719401403545;5621.606487545832;5632.324121105412;5696.11710184922;5392.595555333366;5454.127339625641;5460.372057213392;5468.9726551367785;5469.261649677323;4906.779194936236;4999.350084488993;5101.158308878993;5120.92318615221;5149.819756308586;5826;5861.3552509223955;5868.677222752704;5901.29184497863;5945.732442040032;5738.139279108083;5806.5645076618575;5809.76760929575;5810.389909059657;5812.709364531672;5634.295713035871;5756.769729210785;5904.143899134688;5915.940840591539;5928.614640048383;5502.504774570308;5561.443855614406;5567.47109024642;5578.135389014709;5615.32616086056;5740.39725828782;5788.681622510802;5794.246747922743;5795.82612521625;5799.884002319845;5425.13197888336;5482.314710661168;5572.164175373727;5661.037623603915;5666.801661941812;5592.7762889483965;5607.214989901336;5616;5623.409541998195;5656.2929633795775;5492.780288788564;5532.253145825423;5533.806316778893;5535.39110697831;5560.721963901708;5427.945720542902;5504.22940788282;5516.834494965157;5627.465390787868;5630.605857590093;5506.972465137648;5639.971800140973;5662.094064949588;5669.610945318424;5697.088465845445 09:23:34 INFO - 2016-01-23 09:23:34,839 DEBUG : BROWSER_OUTPUT: |1;ext.html;583.4661284924294;617.6232498176112;624.2259598098358;631.3434028610245;633.9302676705564;32739.50890736638;38788.80605596972;39151.804240978825;39274.21451570967;39333.21333573327;42450;42579.36130958038;42766.57233427665;42887.78556107215;42950.35574466385;7921.485103468285;8041.959790201041;8817.765512828226;9020;9028;10798.78402431951;10998.725031874186;11047.447627618627;11048.281861678965;11065.778684426306;10742.570297188104;10841.295315804487;10844.783104337908;10862.293950893167;10874.021338079572;8179.713710020121;8356.373272004625;10704.036636702695;10807.297525660806;10963;10564.207684423698;10658.147348212124;10670.253082284244;10683.14534837211;10721.571137154504;41864.16271674549;42433;42466.36300455496;42480;42490.36264456036;3706.870259540903;4260.488741351058;4310.698251122422;4310.849120280775;4381.211381951248;32495;32549.349013019844;32631.67368326334;32821.6717832821;33057.50413743796;3381.5773028371455;3441.6730410611203;3444.48332750087;3491.214476742738;3511.0695665648623;305813;305862;306545;307213;307333;59231.111533327035;60560.09159862606;64442;65029;65364.346356535825;64321;64423.35576644267;64783.67608161929;65201.34798652048;65207;14501;14541.27293635314;14710.264486775619;14715.779263311058;14755.483558075415;12078.094142939139;12122.818157727641;12135.332556709465;12162.209456385306;12336.876631233752;21353.25263615766;21695;21716.565668686537;21718.782812171678;21746.347609571738;8155.673773049011;8382.87425688615;8397.664093436317;8442.240198382175;8557.48655080697;1109.3953795181646;1120.2662256222188;1122.3882983773863;1141.4349896801136;1179.7876382251106;29634.55548166779;29761.404771904872;30789.69210307913;30820.537691934638;30844.383112338077;1129.5538262386476;1157.9983314432955;1205.698575356161;1227.895628871538;1233.2908577567755;13622.523211687543;15697.843021569866;15881.126538040287;16185.352585896433;16606.418775342805;351060;351238;352125;352148;352872;57350.13974790381;57355.42644573501;57702;57722.711386442796;57772;1348.5886804524714;1391.7077413743025;1490.761478163467;1490.8061951946177;1596.9520914372588;27659.723402765714;27739.23456814631;28406.431871362045;30158.547621785252;30196.396072077994;43384.13231735411;43617.56382436135;44466.3330050043;51090.48909511006;52407.21389179089;339666;340176;344602;345150;345545;31269.374612508076;31478.52782208223;31643.20891977719;31651.20871978219;31663.683363166074;39925.60074399335;40171.39742903917;41036.1792764149;41055;42093.36859947164;37148.62851371452;37353.066173345884;38457.4231386535;38501.80749096349;38546.61453385543;37011.81494092512;38005;38679.22641547097;38860.805695971336;39089.80455097706;37438.8128059358;37720.811395942845;37751.05622359463;37813.243735125696;37843.62156378401;32525.512117317787;34073;34098.147546310545;34361;34867;26170.198982414488;26177.869110654327;26267.605985909846;26386;26651 09:23:34 INFO - 2016-01-23 09:23:34,840 DEBUG : BROWSER_OUTPUT: |2;jquery.html;385.16995873891767;387.20429517341853;387.8429236159355;387.90884142226554;392.48584354495614;14973.25133743314;15776.290066946985;16678.166091695428;18335.724964125533;18338;14499.20254386011;14618.41526338948;15752.684946301068;18267.634647307044;18636;6100.2679678438535;6246.500279977591;6251.531135164858;6258.092576576394;6258.342873998233;4537.750423726694;4653.371794807708;6147.815565533041;6698.56459330142;6890;6789.864202715943;6811.829704257384;6828.965855170742;6867.450603951697;6867.931320686804;6964.616946067964;6972.965135174291;6995.930040699604;7004.08946836913;7027.332403421692;6601.3728695773825;6683.465322774215;6778.593284402951;6800.421964133055;6806.965965170192;24745.752542474525;24752.25743227706;25185.62221566678;25200.495990080097;25893.611595826078;2605.3226161198227;2605.452854900473;2633.8814753336146;2690.5695088785897;2692.744189302013;14689.77965330521;14880.181590012546;15385.923070384688;15637.843621563867;16128.516144515683;2040.7142999980015;2076.397844625057;2098.821600163988;2113.260358874397;2141.550274442369;73176.26823731748;75222;76819.6159019207;77586;77944;16893;16935.661286774197;16944.830551694573;16955.745663815054;16978.06620635877;26495.205143845717;26529.33676658099;27005.324866878484;27292.181234562995;27396.315092122855;9847.950760246153;9884.061014203638;9884.159846413135;9937.503124843728;9989.250806189564;8564.186402291773;8564.914350856536;8574.356923230782;8605.741827745178;8611.397202195787;6955.312941799515;8567.957160214159;8587.313014958789;8634.697785577475;8661.090585488433;1827.1412436154985;1854.2212270846278;1901.4485799118102;1914.0716752375088;1933.9129739161772;830.5515021888112;949.7578117580111;988.357567581078;1043.1602559939322;1043.89561043895;16025.519234422985;16150.192490375433;16173.919130404272;16617.584560386087;16728;943.1888575824785;989.4607438945792;1031.3554028732042;1035.6582327831798;1037.1650821088945;9255.67605133817;9524.761880953032;9559.235261179088;9616.278779091595;9806.901930980599;599107;620562;625402;628232;631853;80638.19361806424;85770.5711471451;88130;88447.55776221078;88757.55621221982;1142.7485953090306;1166.1894982986744;1169.4678921090883;1185.6265276437894;1191.0352614382377;16209.35162593483;16232.107234101766;16298.348066077224;16500.009999399594;16701.16494175262;36972.26055478929;37978.43032354572;38685;38792.80603596964;38879.6112038876;49651;50070;52285.215721764966;55135.72432137813;55903;9155.313351498531;9277.582508786985;9339.53302334867;9544.522773861141;9574.617015319309;11284.66146015588;11299.887001129884;11368.886311136783;11394.544218231178;16168.353265869704;12707.428165732748;13095.214287142802;15160;15220.315085821387;15258.465953691568;14896.478623248568;14960.326785294905;15144.848551514784;15275.236238188269;15526.22368881574;15884.205789710237;16072.116033618264;16333.836661633231;16397.59006024859;16530.752038719187;9682.70951871445;9704.75738106553;9753.609855605697;9820.361676491006;9849.65526206608;14732;15440.150791706565;15572.766408503654;15912;16040.67918641644 09:23:34 INFO - 2016-01-23 09:23:34,841 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1365.1808914651203;1501.939922403104;1509.1171664576216;1817.9545511362219;1818.2090790506127;6707.798766037021;7566.356859666928;7637.427192960522;7652.732154374598;7665.5017423867475;7721.343685786715;7892.6448309826055;7900.723474678387;7902.644381002854;7906.209379062091;1917.300185432314;1923.7306777051224;2224.5217278285168;2246.853954492961;2286.9313920582326;2290.530441259538;2297.5060362022164;2307.1232931486097;2327.8952447139836;2330.8484948478294;2260.1863329201474;2336.042222688698;2336.100601268515;2338.7427382987858;2339.7543257957923;2286.9085236590518;2290.6220473621834;2312.433453803829;2320.860748355104;2321.048370168223;2297.9885100574556;2308.111377119821;2329.6621989811383;2331.790138887491;2344.4490544721975;7664.693412263503;7712.961435192844;7723.266289702526;7745.419093568005;7751.379889608874;4646.140464014168;4712.929306060412;4717.339572459859;4734.786934587952;4739.834105806315;2933.574631678395;2961.763058955278;3011.834349110798;3040.2247426905997;3042.7261546460923;917.3853518142861;932.2681694869563;934.0845970948509;971.3152227679469;991.1080027974808;10592.523336449878;10594.152467802634;10601.310914790514;10603.257771955967;10614.787704245871;4268.637165840938;4289.378040184156;4328.7402755834755;4331.4152589400655;4349.3475978603465;9080.046595107418;9089.22741566961;9098.636054557743;9118.81762364749;9135.817283654289;4769.95230047702;4778.235482322882;4802.111609352211;4813.711177329372;4814.15752243356;4378.496472905656;4388.034632380871;4388.539203383662;4416.933745993812;4423.690341676116;3344.41472742274;3375.6793104655017;3410.5225268462436;3447.3450044491465;3731.496248006484;1209.5161935225804;1229.65569640499;1236.1532350340046;1240.503798480615;1249.188027781931;1118.0608289037248;1130.4912789244872;1131.3890499130541;1136.3068528197794;1173.1553281637207;7463.626818659045;7471.3649339806725;7522.059742532184;7546.962265188639;7551.093868735787;1153.653903828848;1164.574930150489;1172;1186.2526608236756;1198.7722332756748;5820.970895145582;5867.882642347129;5891.7054147292465;5930.822075337746;5932.2584676915385;9315.25477961775;9404.294677899185;9495.43027418357;9518.428894266366;9520.809583808285;9177.816443671089;9227.169554740105;9232.95383523078;9338.859917101248;9346.813063738824;7235.782926512213;7351.154617218981;7361.668724907392;7432.554046757212;7472.252774722484;7149.7140114396925;7201.747938822116;7243.855122897407;7253.492255542062;7255.56466612005;8083.272505474535;8167.959160204398;8233.79415514593;8295.460795048302;8301;8344.040435350013;8438.409311348147;8526.403151779316;8529.74410767678;8540.70107546233;6949.30506949301;7003.7548685795755;7025.5082144251455;7025.648717564203;7032.753853615099;6721.764738234138;6869.931300686929;7328.78013659591;7579.317861392281;7580.090389153116;6810;7183.712651493882;7191.20896701352;7192.640367981685;7286.890696639661;7099.964500177466;7120.679569419285;7161.57030578167;7212.3148300911325;7232.45756568249;6944.826379340557;6964.860702786017;6996.440284777307;7005.684744186422;7127.679254433459;6346.936530634634;6355.237371515448;6362.45919096882;6389.87220255583;6409.4231519163495;6587.110740050223;6588.143541339595;6611.768588099393;6622.966885165543;6623.701933413105 09:23:34 INFO - 2016-01-23 09:23:34,842 DEBUG : BROWSER_OUTPUT: |4;prototype.html;357.8300307354007;360.0315152240473;365.66541614422806;367.4616686554201;373.2553555656464;5964.850878728023;6088.939110608892;6103.664298463583;6124.755009799603;6159.414855588712;5920.911186332209;6079.7264123114455;6116.908246376308;6123.663198524079;6127.908081378771;4691.390119284505;4731.408573928259;4750.61995040396;4759.666823322369;4763.404574428197;4380.904773806548;4544.97738008947;4611.769411529427;4687.226607609758;4723.881902952437;4679.672422930396;4695.389599352096;4702.7883745231375;4725.834595789148;4730;4652.95347046532;4671;4682.34447177392;4702.553257440555;4720.504347043579;4142.668586513072;4144.191882582898;4152.563980782004;4158.126793373365;4158.854440094612;5695.373508914016;5789.594728369016;5793.391693872167;5810.389909059572;5811.331696854873;5525.861853453655;5534.308211473566;5535.806246781343;5539.418361072069;5546.251256080418;6161.075838624198;6459.8695228334855;6460.030995350688;6463.870722585522;6467.191601049869;4498.977505112486;4513.638908887281;4644.907101857978;4657.138429390574;4658.091672123937;6703.597784132968;6747.257801641816;6749.325067493261;6759.155105611848;6762.898556521656;4768.046390721869;4772.427308722976;4772.7136371817805;4773.546513081252;4779.091972525209;4732.148213321635;4829.010052939139;4837.733924634179;4848.7090774553635;4859.635527335464;5501;5509.3664228614225;5514.310711161105;5517.972410137923;5520.889582208413;6543;6545.541812073205;6550.148480697479;6553.508486863504;6554.80335589933;1701.4045084220425;1706.5562953632145;1708.5557754983793;1709.9914500427667;1711.1273250642262;1111.660943412267;1112.2770199370475;1122.7810576937582;1123.6572845282267;1131.2646779593165;6261.467775239064;6305.432511074026;6312.52656050798;6324.810255692337;6326.367363263729;6652.933470665232;6662.067310576524;6666.366695163952;6669.966650166718;6675.7997260082275;6316.715747791269;6317.905231421532;6319.3364696706185;6320.71556779946;6323.462505686976;3577.266660334677;3653.214558869856;3671.2290419011774;3684.2999830032745;3686.5576130863933;7020.613866237304;7027.262137475594;7029.5430796998035;7031.507794454441;7031.824204394931;6869.278725733726;6991.615461149584;7000.229974702778;7000.859982800315;7006.614636195059;7002.614856182959;7004.614746188907;7015.824604384931;7017.7192912282935;7020.368166865007;6483.870322593616;6668.566543174678;6670.39966403026;6801.455883529206;6819.965900170666;6697.497687673346;6709.832254193489;6719.059331693665;6720.865582688221;6739.292374300824;6931.445484361138;6976.511644184859;6981.685824137824;6982.755603553851;6984.022236886942;4624.930626040679;4669.603083737785;4704.623630109514;4706.482286948364;4723.480417154042;6702.296258892746;6806.18325800907;6806.897896531655;6807.115075040213;6811.421029212374;6588.571742836892;6596.30738772422;6598.736050558116;6599.637019963948;6610.3720146586;6454.483641308778;6566.540342176002;6575.309592492719;6588.868222635425;6591.143151390289;6483.222013358427;6495.1556297681;6500.609963402211;6502.642354670538;6509.837254068686;6560.44236239925;6626.635535045619;6628.27089020217;6638.535302528777;6640.169978752656;6349.8730025400155;6433.099366088845;6460.063290822898;6462.709178086903;6475.737231239863 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: |5;yui.html;760.0195747485743;811.4319976016786;890.2477406591428;892.3664198419121;917.0874979395503;12361.876381236185;15206.543803685894;15500.922495387535;15502;15557.455489057871;14657.193854338007;14675.486357977474;14738.115713057197;14740.852591474082;14752.483663071795;757.2994979643835;919.9632014719404;923.4597760310227;923.7228831350601;925.1026504290827;773.8684423647965;901.6483571407157;917.5228880981886;917.8669092981547;918.5590916360151;959.0792838874688;964.2864280432465;969.0987381734984;970.7379007667926;971.5725080964388;802.8434455281223;810.7567729681072;938.9248860091176;961.9326647134703;963.5808423335836;702.4696354252558;702.6451641920848;704.503325155764;705.717712914833;706.4948561778302;13502;13540.729185416336;13602.455901763917;13623.795643065361;13689.178649281075;11571.884281157165;11843.526258949632;12086.456109475095;12315.75368492625;12370.75258494834;10063.89936100637;10228;10241.231907606885;10316.58733650653;10424.426656533888;3711.3690672585726;3764.887053388403;3768.886933391975;3774.2640185163905;3775.528058992626;53449.73275133599;53754.46245537534;53866.461335386535;54116.458835411926;54132.188017179775;9228.676996305097;9255.76860578491;9258.305627077996;9291.628334866666;9349;19124.617507649768;19157;19176;19179.61640767177;19187.520311992033;7258.564486130849;7646.3882889368715;7672.1560628330835;7915.960420197862;7948.205179482005;4396.252637051709;4465;4465.955340446554;4482.955170448319;4505.549445055468;3138.1370123215843;3178.8410579470933;3185.2355434695505;3188.1232661018407;3188.362327534456;526.799816069891;546.3389298948317;548.6817645765447;549.4176173256282;559.152883381677;633.2585390177013;706.8268274272837;723.3309189000154;724.2359310926918;733.479229746875;9660.903390966141;9985.950070249603;10158.238132140119;10317.896821031694;10327.84508232377;569.4789267819898;724.5906063074347;741.8862407947942;744.129368638694;750.0767912170695;4334.19817333788;4439.112177564435;4501.819927202875;4554.681172317906;4594.563516465931;60483.69758151181;60821;60979.39020609826;61303.693481532304;61508;53484;53978.19032714512;54246.186307205426;54553.45446545563;54562.72718636461;49202.26196607054;49851.50148498541;49944.25083623749;50242;50391;9022.323325750463;9158.725238242863;9192.126747958933;9307.860382094139;9441.622335106793;35936.82031589825;36090.27819443649;37093;37670;37733.4339984895;41408.792956035024;42671;43027.3545896818;47620.523794762994;50098.749506252236;11197.104231661284;11666.650000499998;11753.92631260923;11797.88202117968;11901.82147267809;12754.043446741716;12857.549985750455;13208;13713.725725485236;14270.429182832571;11772.587959421378;11920.701982450508;12173.87826121763;12244.448999794853;12708.046896482985;11505.654830355103;11584.652460426201;11631.59289424866;11644.592439264585;11707.180497365103;12173.2696038238;12262.509499619915;12599.307038112625;12710.682232944251;12783.552575659807;10527.473626318806;10791.082757965347;10944.671659850217;11161;11224.214304998899;10493.737656558647;10571.94714026425;10746.892531074589;10780.568777248822;10845.403502807421 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,867 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: __startTimestamp1453569814835__endTimestamp 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,843 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5358.03 Median:5548.67 stddev:569.91 (10.3%) stddev-sans-first:546.53 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: Values: 3224.6 3267.5 3298.3 3300.0 3317.1 5309.0 5314.8 5332.4 5333.8 5374.9 5302.6 5313.6 5336.3 5348.1 5355.0 4772.2 4902.0 4929.3 4943.8 4986.0 4889.0 4948.2 4960.2 4966.1 4975.9 4775.4 4840.4 4914.4 4924.0 4925.6 4912.3 4961.5 4988.7 5013.3 5016.4 4975.4 4982.0 4989.8 5007.2 5020.8 5051.3 5163.0 5257.3 5277.3 5293.0 5731.0 5841.4 5842.1 5845.4 5861.1 5835.8 5839.1 5844.9 5850.9 5854.5 5299.8 5402.9 5421.7 5452.6 5456.1 5779.2 5850.0 5884.6 5891.9 5924.5 5649.2 5652.3 5727.8 5751.2 5765.9 5636.6 5740.3 5767.7 5821.0 5843.7 5809.9 5821.9 5828.0 5836.4 5840.2 5793.3 5798.5 5801.7 5813.7 5818.7 4324.2 4398.8 4414.1 4491.1 4510.1 4046.9 4047.5 4054.5 4056.7 4089.7 5548.7 5574.9 5576.1 5578.3 5602.8 5453.0 5612.0 5621.6 5632.3 5696.1 5392.6 5454.1 5460.4 5469.0 5469.3 4906.8 4999.4 5101.2 5120.9 5149.8 5826.0 5861.4 5868.7 5901.3 5945.7 5738.1 5806.6 5809.8 5810.4 5812.7 5634.3 5756.8 5904.1 5915.9 5928.6 5502.5 5561.4 5567.5 5578.1 5615.3 5740.4 5788.7 5794.2 5795.8 5799.9 5425.1 5482.3 5572.2 5661.0 5666.8 5592.8 5607.2 5616.0 5623.4 5656.3 5492.8 5532.3 5533.8 5535.4 5560.7 5427.9 5504.2 5516.8 5627.5 5630.6 5507.0 5640.0 5662.1 5669.6 5697.1 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50853.02 Median:18901.34 stddev:87567.93 (463.3%) stddev-sans-first:87732.24 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: Values: 583.5 617.6 624.2 631.3 633.9 32739.5 38788.8 39151.8 39274.2 39333.2 42450.0 42579.4 42766.6 42887.8 42950.4 7921.5 8042.0 8817.8 9020.0 9028.0 10798.8 10998.7 11047.4 11048.3 11065.8 10742.6 10841.3 10844.8 10862.3 10874.0 8179.7 8356.4 10704.0 10807.3 10963.0 10564.2 10658.1 10670.3 10683.1 10721.6 41864.2 42433.0 42466.4 42480.0 42490.4 3706.9 4260.5 4310.7 4310.8 4381.2 32495.0 32549.3 32631.7 32821.7 33057.5 3381.6 3441.7 3444.5 3491.2 3511.1 305813.0 305862.0 306545.0 307213.0 307333.0 59231.1 60560.1 64442.0 65029.0 65364.3 64321.0 64423.4 64783.7 65201.3 65207.0 14501.0 14541.3 14710.3 14715.8 14755.5 12078.1 12122.8 12135.3 12162.2 12336.9 21353.3 21695.0 21716.6 21718.8 21746.3 8155.7 8382.9 8397.7 8442.2 8557.5 1109.4 1120.3 1122.4 1141.4 1179.8 29634.6 29761.4 30789.7 30820.5 30844.4 1129.6 1158.0 1205.7 1227.9 1233.3 13622.5 15697.8 15881.1 16185.4 16606.4 351060.0 351238.0 352125.0 352148.0 352872.0 57350.1 57355.4 57702.0 57722.7 57772.0 1348.6 1391.7 1490.8 1490.8 1597.0 27659.7 27739.2 28406.4 30158.5 30196.4 43384.1 43617.6 44466.3 51090.5 52407.2 339666.0 340176.0 344602.0 345150.0 345545.0 31269.4 31478.5 31643.2 31651.2 31663.7 39925.6 40171.4 41036.2 41055.0 42093.4 37148.6 37353.1 38457.4 38501.8 38546.6 37011.8 38005.0 38679.2 38860.8 39089.8 37438.8 37720.8 37751.1 37813.2 37843.6 32525.5 34073.0 34098.1 34361.0 34867.0 26170.2 26177.9 26267.6 26386.0 26651.0 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,844 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33233.96 Median:37475.35 stddev:101438.61 (270.7%) stddev-sans-first:101693.18 09:23:34 INFO - 2016-01-23 09:23:34,845 DEBUG : BROWSER_OUTPUT: Values: 385.2 387.2 387.8 387.9 392.5 14973.3 15776.3 16678.2 18335.7 18338.0 14499.2 14618.4 15752.7 18267.6 18636.0 6100.3 6246.5 6251.5 6258.1 6258.3 4537.8 4653.4 6147.8 6698.6 6890.0 6789.9 6811.8 6829.0 6867.5 6867.9 6964.6 6973.0 6995.9 7004.1 7027.3 6601.4 6683.5 6778.6 6800.4 6807.0 24745.8 24752.3 25185.6 25200.5 25893.6 2605.3 2605.5 2633.9 2690.6 2692.7 14689.8 14880.2 15385.9 15637.8 16128.5 2040.7 2076.4 2098.8 2113.3 2141.6 73176.3 75222.0 76819.6 77586.0 77944.0 16893.0 16935.7 16944.8 16955.7 16978.1 26495.2 26529.3 27005.3 27292.2 27396.3 9848.0 9884.1 9884.2 9937.5 9989.3 8564.2 8564.9 8574.4 8605.7 8611.4 6955.3 8568.0 8587.3 8634.7 8661.1 1827.1 1854.2 1901.4 1914.1 1933.9 830.6 949.8 988.4 1043.2 1043.9 16025.5 16150.2 16173.9 16617.6 16728.0 943.2 989.5 1031.4 1035.7 1037.2 9255.7 9524.8 9559.2 9616.3 9806.9 599107.0 620562.0 625402.0 628232.0 631853.0 80638.2 85770.6 88130.0 88447.6 88757.6 1142.7 1166.2 1169.5 1185.6 1191.0 16209.4 16232.1 16298.3 16500.0 16701.2 36972.3 37978.4 38685.0 38792.8 38879.6 49651.0 50070.0 52285.2 55135.7 55903.0 9155.3 9277.6 9339.5 9544.5 9574.6 11284.7 11299.9 11368.9 11394.5 16168.4 12707.4 13095.2 15160.0 15220.3 15258.5 14896.5 14960.3 15144.8 15275.2 15526.2 15884.2 16072.1 16333.8 16397.6 16530.8 9682.7 9704.8 9753.6 9820.4 9849.7 14732.0 15440.2 15572.8 15912.0 16040.7 09:23:34 INFO - 2016-01-23 09:23:34,845 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,845 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5466.71 Median:6587.63 stddev:2813.01 (42.7%) stddev-sans-first:2804.00 09:23:34 INFO - 2016-01-23 09:23:34,846 DEBUG : BROWSER_OUTPUT: Values: 1365.2 1501.9 1509.1 1818.0 1818.2 6707.8 7566.4 7637.4 7652.7 7665.5 7721.3 7892.6 7900.7 7902.6 7906.2 1917.3 1923.7 2224.5 2246.9 2286.9 2290.5 2297.5 2307.1 2327.9 2330.8 2260.2 2336.0 2336.1 2338.7 2339.8 2286.9 2290.6 2312.4 2320.9 2321.0 2298.0 2308.1 2329.7 2331.8 2344.4 7664.7 7713.0 7723.3 7745.4 7751.4 4646.1 4712.9 4717.3 4734.8 4739.8 2933.6 2961.8 3011.8 3040.2 3042.7 917.4 932.3 934.1 971.3 991.1 10592.5 10594.2 10601.3 10603.3 10614.8 4268.6 4289.4 4328.7 4331.4 4349.3 9080.0 9089.2 9098.6 9118.8 9135.8 4770.0 4778.2 4802.1 4813.7 4814.2 4378.5 4388.0 4388.5 4416.9 4423.7 3344.4 3375.7 3410.5 3447.3 3731.5 1209.5 1229.7 1236.2 1240.5 1249.2 1118.1 1130.5 1131.4 1136.3 1173.2 7463.6 7471.4 7522.1 7547.0 7551.1 1153.7 1164.6 1172.0 1186.3 1198.8 5821.0 5867.9 5891.7 5930.8 5932.3 9315.3 9404.3 9495.4 9518.4 9520.8 9177.8 9227.2 9233.0 9338.9 9346.8 7235.8 7351.2 7361.7 7432.6 7472.3 7149.7 7201.7 7243.9 7253.5 7255.6 8083.3 8168.0 8233.8 8295.5 8301.0 8344.0 8438.4 8526.4 8529.7 8540.7 6949.3 7003.8 7025.5 7025.6 7032.8 6721.8 6869.9 7328.8 7579.3 7580.1 6810.0 7183.7 7191.2 7192.6 7286.9 7100.0 7120.7 7161.6 7212.3 7232.5 6944.8 6964.9 6996.4 7005.7 7127.7 6346.9 6355.2 6362.5 6389.9 6409.4 6587.1 6588.1 6611.8 6623.0 6623.7 09:23:34 INFO - 2016-01-23 09:23:34,846 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,846 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5484.99 Median:6211.27 stddev:1639.74 (26.4%) stddev-sans-first:1598.54 09:23:34 INFO - 2016-01-23 09:23:34,846 DEBUG : BROWSER_OUTPUT: Values: 357.8 360.0 365.7 367.5 373.3 5964.9 6088.9 6103.7 6124.8 6159.4 5920.9 6079.7 6116.9 6123.7 6127.9 4691.4 4731.4 4750.6 4759.7 4763.4 4380.9 4545.0 4611.8 4687.2 4723.9 4679.7 4695.4 4702.8 4725.8 4730.0 4653.0 4671.0 4682.3 4702.6 4720.5 4142.7 4144.2 4152.6 4158.1 4158.9 5695.4 5789.6 5793.4 5810.4 5811.3 5525.9 5534.3 5535.8 5539.4 5546.3 6161.1 6459.9 6460.0 6463.9 6467.2 4499.0 4513.6 4644.9 4657.1 4658.1 6703.6 6747.3 6749.3 6759.2 6762.9 4768.0 4772.4 4772.7 4773.5 4779.1 4732.1 4829.0 4837.7 4848.7 4859.6 5501.0 5509.4 5514.3 5518.0 5520.9 6543.0 6545.5 6550.1 6553.5 6554.8 1701.4 1706.6 1708.6 1710.0 1711.1 1111.7 1112.3 1122.8 1123.7 1131.3 6261.5 6305.4 6312.5 6324.8 6326.4 6652.9 6662.1 6666.4 6670.0 6675.8 6316.7 6317.9 6319.3 6320.7 6323.5 3577.3 3653.2 3671.2 3684.3 3686.6 7020.6 7027.3 7029.5 7031.5 7031.8 6869.3 6991.6 7000.2 7000.9 7006.6 7002.6 7004.6 7015.8 7017.7 7020.4 6483.9 6668.6 6670.4 6801.5 6820.0 6697.5 6709.8 6719.1 6720.9 6739.3 6931.4 6976.5 6981.7 6982.8 6984.0 4624.9 4669.6 4704.6 4706.5 4723.5 6702.3 6806.2 6806.9 6807.1 6811.4 6588.6 6596.3 6598.7 6599.6 6610.4 6454.5 6566.5 6575.3 6588.9 6591.1 6483.2 6495.2 6500.6 6502.6 6509.8 6560.4 6626.6 6628.3 6638.5 6640.2 6349.9 6433.1 6460.1 6462.7 6475.7 09:23:34 INFO - 2016-01-23 09:23:34,846 DEBUG : BROWSER_OUTPUT: 09:23:34 INFO - 2016-01-23 09:23:34,847 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14657.60 Median:4474.46 stddev:16997.45 (379.9%) stddev-sans-first:17013.09 09:23:34 INFO - 2016-01-23 09:23:34,847 DEBUG : BROWSER_OUTPUT: Values: 760.0 811.4 890.2 892.4 917.1 12361.9 15206.5 15500.9 15502.0 15557.5 14657.2 14675.5 14738.1 14740.9 14752.5 757.3 920.0 923.5 923.7 925.1 773.9 901.6 917.5 917.9 918.6 959.1 964.3 969.1 970.7 971.6 802.8 810.8 938.9 961.9 963.6 702.5 702.6 704.5 705.7 706.5 13502.0 13540.7 13602.5 13623.8 13689.2 11571.9 11843.5 12086.5 12315.8 12370.8 10063.9 10228.0 10241.2 10316.6 10424.4 3711.4 3764.9 3768.9 3774.3 3775.5 53449.7 53754.5 53866.5 54116.5 54132.2 9228.7 9255.8 9258.3 9291.6 9349.0 19124.6 19157.0 19176.0 19179.6 19187.5 7258.6 7646.4 7672.2 7916.0 7948.2 4396.3 4465.0 4466.0 4483.0 4505.5 3138.1 3178.8 3185.2 3188.1 3188.4 526.8 546.3 548.7 549.4 559.2 633.3 706.8 723.3 724.2 733.5 9660.9 9986.0 10158.2 10317.9 10327.8 569.5 724.6 741.9 744.1 750.1 4334.2 4439.1 4501.8 4554.7 4594.6 60483.7 60821.0 60979.4 61303.7 61508.0 53484.0 53978.2 54246.2 54553.5 54562.7 49202.3 49851.5 49944.3 50242.0 50391.0 9022.3 9158.7 9192.1 9307.9 9441.6 35936.8 36090.3 37093.0 37670.0 37733.4 41408.8 42671.0 43027.4 47620.5 50098.7 11197.1 11666.7 11753.9 11797.9 11901.8 12754.0 12857.5 13208.0 13713.7 14270.4 11772.6 11920.7 12173.9 12244.4 12708.0 11505.7 11584.7 11631.6 11644.6 11707.2 12173.3 12262.5 12599.3 12710.7 12783.6 10527.5 10791.1 10944.7 11161.0 11224.2 10493.7 10571.9 10746.9 10780.6 10845.4 09:23:34 INFO - 2016-01-23 09:23:34,847 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:23:34 INFO - 2016-01-23 09:23:34,847 DEBUG : BROWSER_OUTPUT: 09:23:35 INFO - 2016-01-23 09:23:35,395 INFO : Browser exited with error code: 0 09:23:35 INFO - 2016-01-23 09:23:35,411 INFO : Completed test dromaeo_css (00:18:30) 09:23:35 INFO - 2016-01-23 09:23:35,411 INFO : Starting test kraken 09:23:35 INFO - 2016-01-23 09:23:35,411 DEBUG : operating with platform_type : linux_ 09:23:35 INFO - 2016-01-23 09:23:35,411 INFO : Initialising browser for kraken test... 09:23:35 INFO - 2016-01-23 09:23:35,417 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZ2_rbV/profile http://localhost:43750/getInfo.html 09:23:39 INFO - 2016-01-23 09:23:39,091 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:23:39 INFO - 2016-01-23 09:23:39,091 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:23:39 INFO - 2016-01-23 09:23:39,092 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:23:39 INFO - 2016-01-23 09:23:39,092 DEBUG : BROWSER_OUTPUT: __metrics 09:23:39 INFO - 2016-01-23 09:23:39,220 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:23:40 INFO - 2016-01-23 09:23:40,087 INFO : Browser initialized. 09:23:40 INFO - 2016-01-23 09:23:40,087 INFO : Running cycle 1/1 for kraken test... 09:23:40 INFO - 2016-01-23 09:23:40,088 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZ2_rbV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:23:51 INFO - 2016-01-23 09:23:51,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 137621504 09:23:51 INFO - 2016-01-23 09:23:51,883 DEBUG : BROWSER_OUTPUT: 09:23:54 INFO - 2016-01-23 09:23:54,332 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43750/tests/kraken/driver.html?testName=audio-beat-detection) 09:23:54 INFO - 2016-01-23 09:23:54,914 DEBUG : BROWSER_OUTPUT: RSS: Main: 167899136 09:23:54 INFO - 2016-01-23 09:23:54,914 DEBUG : BROWSER_OUTPUT: 09:23:57 INFO - 2016-01-23 09:23:57,462 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43750/tests/kraken/driver.html?testName=audio-dft) 09:23:58 INFO - 2016-01-23 09:23:58,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 166539264 09:23:58 INFO - 2016-01-23 09:23:58,028 DEBUG : BROWSER_OUTPUT: 09:24:00 INFO - 2016-01-23 09:24:00,817 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43750/tests/kraken/driver.html?testName=audio-fft) 09:24:01 INFO - 2016-01-23 09:24:01,387 DEBUG : BROWSER_OUTPUT: RSS: Main: 164200448 09:24:01 INFO - 2016-01-23 09:24:01,387 DEBUG : BROWSER_OUTPUT: 09:24:03 INFO - 2016-01-23 09:24:03,380 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43750/tests/kraken/driver.html?testName=audio-oscillator) 09:24:03 INFO - 2016-01-23 09:24:03,941 DEBUG : BROWSER_OUTPUT: RSS: Main: 165175296 09:24:03 INFO - 2016-01-23 09:24:03,941 DEBUG : BROWSER_OUTPUT: 09:24:06 INFO - 2016-01-23 09:24:06,135 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43750/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:24:06 INFO - 2016-01-23 09:24:06,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 163565568 09:24:06 INFO - 2016-01-23 09:24:06,696 DEBUG : BROWSER_OUTPUT: 09:24:09 INFO - 2016-01-23 09:24:09,546 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43750/tests/kraken/driver.html?testName=imaging-darkroom) 09:24:10 INFO - 2016-01-23 09:24:10,115 DEBUG : BROWSER_OUTPUT: RSS: Main: 163237888 09:24:10 INFO - 2016-01-23 09:24:10,115 DEBUG : BROWSER_OUTPUT: 09:24:13 INFO - 2016-01-23 09:24:13,438 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43750/tests/kraken/driver.html?testName=imaging-desaturate) 09:24:14 INFO - 2016-01-23 09:24:14,005 DEBUG : BROWSER_OUTPUT: RSS: Main: 163082240 09:24:14 INFO - 2016-01-23 09:24:14,005 DEBUG : BROWSER_OUTPUT: 09:24:16 INFO - 2016-01-23 09:24:16,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43750/tests/kraken/driver.html?testName=json-parse-financial) 09:24:17 INFO - 2016-01-23 09:24:17,284 DEBUG : BROWSER_OUTPUT: RSS: Main: 163090432 09:24:17 INFO - 2016-01-23 09:24:17,284 DEBUG : BROWSER_OUTPUT: 09:24:18 INFO - 2016-01-23 09:24:18,840 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43750/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:24:19 INFO - 2016-01-23 09:24:19,401 DEBUG : BROWSER_OUTPUT: RSS: Main: 171708416 09:24:19 INFO - 2016-01-23 09:24:19,402 DEBUG : BROWSER_OUTPUT: 09:24:21 INFO - 2016-01-23 09:24:21,156 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:24:21 INFO - 2016-01-23 09:24:21,745 DEBUG : BROWSER_OUTPUT: RSS: Main: 169369600 09:24:21 INFO - 2016-01-23 09:24:21,746 DEBUG : BROWSER_OUTPUT: 09:24:23 INFO - 2016-01-23 09:24:23,738 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:24:24 INFO - 2016-01-23 09:24:24,311 DEBUG : BROWSER_OUTPUT: RSS: Main: 181223424 09:24:24 INFO - 2016-01-23 09:24:24,311 DEBUG : BROWSER_OUTPUT: 09:24:26 INFO - 2016-01-23 09:24:26,687 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:24:27 INFO - 2016-01-23 09:24:27,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 187871232 09:24:27 INFO - 2016-01-23 09:24:27,260 DEBUG : BROWSER_OUTPUT: 09:24:29 INFO - 2016-01-23 09:24:29,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:24:30 INFO - 2016-01-23 09:24:30,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 186462208 09:24:30 INFO - 2016-01-23 09:24:30,362 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,011 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43750/tests/kraken/driver.html?testName=ai-astar) 09:24:32 INFO - 2016-01-23 09:24:32,261 DEBUG : BROWSER_OUTPUT: RSS: Main: 245133312 09:24:32 INFO - 2016-01-23 09:24:32,261 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;103;110;109;110;97;109;109;111 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;145;128;150;156;122;147;148;122;158;125 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;159;159;159;159;161;161;163;159;160 09:24:32 INFO - 2016-01-23 09:24:32,263 DEBUG : BROWSER_OUTPUT: |3;audio-fft;78;71;73;143;73;73;78;71;149;71 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;146;93;93;92;138;92;92;138 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;109;109;110;110;110;108 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;146;146;145;145;146;145 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;96;96;96;95;96;95;95 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;57;57;57;57;57;60;56;61;53 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;64;65;65;66;65;64;66;64 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;79;79;81;79;81;80;81;81;81 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;116;123;110;109;116;113;119;112;130 09:24:32 INFO - 2016-01-23 09:24:32,264 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;143;134;146;143;143;152;144;144;143;135 09:24:32 INFO - 2016-01-23 09:24:32,265 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;66;65;63;66;63;64;64;67;67 09:24:32 INFO - 2016-01-23 09:24:32,265 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:24:32 INFO - 2016-01-23 09:24:32,265 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:24:32 INFO - 2016-01-23 09:24:32,265 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,903 09:24:32 INFO - 2016-01-23 09:24:32,265 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:24:32 INFO - 2016-01-23 09:24:32,265 DEBUG : BROWSER_OUTPUT: __startTimestamp1453569872261__endTimestamp 09:24:32 INFO - 2016-01-23 09:24:32,265 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,273 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:24:32 INFO - 2016-01-23 09:24:32,273 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:24:32 INFO - 2016-01-23 09:24:32,273 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,273 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.60 Median:110.00 stddev:4.33 (3.9%) stddev-sans-first:4.59 09:24:32 INFO - 2016-01-23 09:24:32,273 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 103.0 110.0 109.0 110.0 97.0 109.0 109.0 111.0 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.10 Median:147.50 stddev:14.28 (9.7%) stddev-sans-first:15.03 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: Values: 145.0 128.0 150.0 156.0 122.0 147.0 148.0 122.0 158.0 125.0 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:1.37 (0.9%) stddev-sans-first:1.41 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: Values: 161.0 159.0 159.0 159.0 159.0 161.0 161.0 163.0 159.0 160.0 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.00 Median:73.00 stddev:30.71 (42.1%) stddev-sans-first:32.36 09:24:32 INFO - 2016-01-23 09:24:32,274 DEBUG : BROWSER_OUTPUT: Values: 78.0 71.0 73.0 143.0 73.0 73.0 78.0 71.0 149.0 71.0 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.00 Median:92.50 stddev:23.34 (25.2%) stddev-sans-first:24.20 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 146.0 93.0 93.0 92.0 138.0 92.0 92.0 138.0 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 110.0 108.0 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 146.0 146.0 145.0 145.0 146.0 145.0 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:2.26 (4.0%) stddev-sans-first:2.28 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: Values: 55.0 57.0 57.0 57.0 57.0 57.0 60.0 56.0 61.0 53.0 09:24:32 INFO - 2016-01-23 09:24:32,275 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 65.0 65.0 66.0 65.0 64.0 66.0 64.0 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:81.00 stddev:0.99 (1.2%) stddev-sans-first:0.97 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: Values: 79.0 79.0 79.0 81.0 79.0 81.0 80.0 81.0 81.0 81.0 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:117.50 stddev:6.78 (5.8%) stddev-sans-first:6.73 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: Values: 124.0 116.0 123.0 110.0 109.0 116.0 113.0 119.0 112.0 130.0 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.70 Median:143.50 stddev:5.12 (3.6%) stddev-sans-first:5.43 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: Values: 143.0 134.0 146.0 143.0 143.0 152.0 144.0 144.0 143.0 135.0 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.50 stddev:1.49 (2.3%) stddev-sans-first:1.58 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: Values: 65.0 66.0 65.0 63.0 66.0 63.0 64.0 64.0 67.0 67.0 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:24:32 INFO - 2016-01-23 09:24:32,276 DEBUG : BROWSER_OUTPUT: 09:24:33 INFO - 2016-01-23 09:24:33,103 INFO : Browser exited with error code: 0 09:24:33 INFO - 2016-01-23 09:24:33,151 INFO : Completed test kraken (00:00:57) 09:24:33 INFO - 2016-01-23 09:24:33,151 INFO : Starting test v8_7 09:24:33 INFO - 2016-01-23 09:24:33,151 DEBUG : operating with platform_type : linux_ 09:24:33 INFO - 2016-01-23 09:24:33,151 INFO : Initialising browser for v8_7 test... 09:24:33 INFO - 2016-01-23 09:24:33,157 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQENsDe/profile http://localhost:43750/getInfo.html 09:24:36 INFO - 2016-01-23 09:24:36,613 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:24:36 INFO - 2016-01-23 09:24:36,614 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:24:36 INFO - 2016-01-23 09:24:36,614 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:24:36 INFO - 2016-01-23 09:24:36,614 DEBUG : BROWSER_OUTPUT: __metrics 09:24:36 INFO - 2016-01-23 09:24:36,758 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:24:37 INFO - 2016-01-23 09:24:37,778 INFO : Browser initialized. 09:24:37 INFO - 2016-01-23 09:24:37,778 INFO : Running cycle 1/1 for v8_7 test... 09:24:37 INFO - 2016-01-23 09:24:37,778 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQENsDe/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:24:49 INFO - 2016-01-23 09:24:49,560 DEBUG : BROWSER_OUTPUT: RSS: Main: 136863744 09:24:49 INFO - 2016-01-23 09:24:49,561 DEBUG : BROWSER_OUTPUT: 09:25:10 INFO - 2016-01-23 09:25:10,916 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43750/tests/v8_7/run.html (next: http://localhost:43750/tests/v8_7/run.html) 09:25:11 INFO - 2016-01-23 09:25:11,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 424734720 09:25:11 INFO - 2016-01-23 09:25:11,166 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: |0;Richards;165.48072149594572 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;173.40038148083926 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.79854504756577 09:25:11 INFO - 2016-01-23 09:25:11,168 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5196.891191709845 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: |4;RayTrace;958.7727708533077 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: |5;Earley;559.2841163310962 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: |6;Boyer;11359.550561797752 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: |7;RegExp;24261.904761904763 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: |8;Splay;523.5602094240837 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:25:11 INFO - 2016-01-23 09:25:11,169 DEBUG : BROWSER_OUTPUT: __startTimestamp1453569911166__endTimestamp 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.48 Median:165.48 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: Values: 165.5 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:173.40 Median:173.40 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,170 DEBUG : BROWSER_OUTPUT: Values: 173.4 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.80 Median:279.80 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: Values: 279.8 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5196.89 Median:5196.89 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: Values: 5196.9 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,171 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:958.77 Median:958.77 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: Values: 958.8 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:559.28 Median:559.28 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: Values: 559.3 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11359.55 Median:11359.55 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: Values: 11359.6 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,172 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24261.90 Median:24261.90 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: Values: 24261.9 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:523.56 Median:523.56 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: Values: 523.6 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: Values: 4835.7 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:25:11 INFO - 2016-01-23 09:25:11,173 DEBUG : BROWSER_OUTPUT: 09:25:11 INFO - 2016-01-23 09:25:11,951 INFO : Browser exited with error code: 0 09:25:11 INFO - 2016-01-23 09:25:11,967 INFO : Completed test v8_7 (00:00:38) 09:25:12 INFO - 2016-01-23 09:25:12,210 INFO : Completed test suite (00:20:06) 09:25:12 INFO - 2016-01-23 09:25:12,210 DEBUG : Working with test: dromaeo_css 09:25:12 INFO - 2016-01-23 09:25:12,210 DEBUG : Generating results file: dromaeo_css 09:25:12 INFO - 2016-01-23 09:25:12,212 DEBUG : Working with test: kraken 09:25:12 INFO - 2016-01-23 09:25:12,212 DEBUG : Generating results file: kraken 09:25:12 INFO - 2016-01-23 09:25:12,213 INFO : javascript benchmark 09:25:12 INFO - 2016-01-23 09:25:12,213 DEBUG : Working with test: v8_7 09:25:12 INFO - 2016-01-23 09:25:12,213 DEBUG : Generating results file: v8_7 09:25:12 INFO - 2016-01-23 09:25:12,214 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:25:12 INFO - 2016-01-23 09:25:12,362 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 09:25:12 INFO - 2016-01-23 09:25:12,362 DEBUG : process_Request line: dromaeo_css 7382.17 graph.html#tests=[[72,52,35]] 09:25:12 INFO - 2016-01-23 09:25:12,362 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:25:12 INFO - 2016-01-23 09:25:12,505 DEBUG : process_Request line: kraken 1479.80 graph.html#tests=[[232,52,35]] 09:25:12 INFO - 2016-01-23 09:25:12,505 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:25:12 INFO - 2016-01-23 09:25:12,639 DEBUG : process_Request line: v8_7 22913.11 graph.html#tests=[[230,52,35]] 09:25:12 INFO - 2016-01-23 09:25:12,640 INFO : javascript benchmark 09:25:12 INFO - 2016-01-23 09:25:12,644 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3224.596925384327, 3267.542544043833, 3298.257891974306, 3300.026492184805, 3317.1209629448194, 5308.973455132728, 5314.76083576239, 5332.413434522205, 5333.786648534064, 5374.865628359288, 5302.628815982883, 5313.57491400688, 5336.252924590561, 5348.144296912495, 5355.036093503168, 4772.212584923501, 4901.970586176907, 4929.260610908356, 4943.777530011158, 4985.977874535712, 4888.951110488885, 4948.2330238812865, 4960.23116416956, 4966.081274964125, 4975.875603109915, 4775.379200703904, 4840.394950631171, 4914.385701787277, 4923.9753801230945, 4925.630577706668, 4912.263160525914, 4961.454240033593, 4988.65079444439, 5013.273075404082, 5016.372953380827, 4975.402951645826, 4982.0, 4989.800407983676, 5007.24891266313, 5020.824271150492, 5051.318072060299, 5163.019026385013, 5257.290265814143, 5277.261183434322, 5292.994331077083, 5730.971345143247, 5841.386654401312, 5842.094475356326, 5845.444682755132, 5861.120831875211, 5835.79574714883, 5839.0949402842625, 5844.941550584483, 5850.853728656775, 5854.47309742121, 5299.814506492255, 5402.945970540323, 5421.728913554385, 5452.645578037414, 5456.072467680504, 5779.162021506942, 5849.970750146307, 5884.6469211847425, 5891.882162356815, 5924.496417804448, 5649.237352957381, 5652.3217213934595, 5727.828165155052, 5751.194832723422, 5765.88468230633, 5636.605437619327, 5740.253767010262, 5767.740451679684, 5820.970895145497, 5843.707814609337, 5809.912851307234, 5821.941780582225, 5828.0, 5836.416358364129, 5840.153177789197, 5793.275840519935, 5798.507126894261, 5801.7389217485315, 5813.709314534256, 5818.709064546756, 4324.17840610283, 4398.8020539075815, 4414.1171765647, 4491.124230774967, 4510.120526497367, 4046.8785936421955, 4047.534533528682, 4054.5134583850127, 4056.675465962775, 4089.7137200396282, 5548.667079975215, 5574.916376254359, 5576.1356989666665, 5578.30271216098, 5602.831915042555, 5452.972735136299, 5611.9719401403545, 5621.606487545832, 5632.324121105412, 5696.11710184922, 5392.595555333366, 5454.127339625641, 5460.372057213392, 5468.9726551367785, 5469.261649677323, 4906.779194936236, 4999.350084488993, 5101.158308878993, 5120.92318615221, 5149.819756308586, 5826.0, 5861.3552509223955, 5868.677222752704, 5901.29184497863, 5945.732442040032, 5738.139279108083, 5806.5645076618575, 5809.76760929575, 5810.389909059657, 5812.709364531672, 5634.295713035871, 5756.769729210785, 5904.143899134688, 5915.940840591539, 5928.614640048383, 5502.504774570308, 5561.443855614406, 5567.47109024642, 5578.135389014709, 5615.32616086056, 5740.39725828782, 5788.681622510802, 5794.246747922743, 5795.82612521625, 5799.884002319845, 5425.13197888336, 5482.314710661168, 5572.164175373727, 5661.037623603915, 5666.801661941812, 5592.7762889483965, 5607.214989901336, 5616.0, 5623.409541998195, 5656.2929633795775, 5492.780288788564, 5532.253145825423, 5533.806316778893, 5535.39110697831, 5560.721963901708, 5427.945720542902, 5504.22940788282, 5516.834494965157, 5627.465390787868, 5630.605857590093, 5506.972465137648, 5639.971800140973, 5662.094064949588, 5669.610945318424, 5697.088465845445], "unit": "ms", "name": "dojo.html", "value": 5322.772372448695}, {"lowerIsBetter": false, "replicates": [583.4661284924294, 617.6232498176112, 624.2259598098358, 631.3434028610245, 633.9302676705564, 32739.50890736638, 38788.80605596972, 39151.804240978825, 39274.21451570967, 39333.21333573327, 42450.0, 42579.36130958038, 42766.57233427665, 42887.78556107215, 42950.35574466385, 7921.485103468285, 8041.959790201041, 8817.765512828226, 9020.0, 9028.0, 10798.78402431951, 10998.725031874186, 11047.447627618627, 11048.281861678965, 11065.778684426306, 10742.570297188104, 10841.295315804487, 10844.783104337908, 10862.293950893167, 10874.021338079572, 8179.713710020121, 8356.373272004625, 10704.036636702695, 10807.297525660806, 10963.0, 10564.207684423698, 10658.147348212124, 10670.253082284244, 10683.14534837211, 10721.571137154504, 41864.16271674549, 42433.0, 42466.36300455496, 42480.0, 42490.36264456036, 3706.870259540903, 4260.488741351058, 4310.698251122422, 4310.849120280775, 4381.211381951248, 32495.0, 32549.349013019844, 32631.67368326334, 32821.6717832821, 33057.50413743796, 3381.5773028371455, 3441.6730410611203, 3444.48332750087, 3491.214476742738, 3511.0695665648623, 305813.0, 305862.0, 306545.0, 307213.0, 307333.0, 59231.111533327035, 60560.09159862606, 64442.0, 65029.0, 65364.346356535825, 64321.0, 64423.35576644267, 64783.67608161929, 65201.34798652048, 65207.0, 14501.0, 14541.27293635314, 14710.264486775619, 14715.779263311058, 14755.483558075415, 12078.094142939139, 12122.818157727641, 12135.332556709465, 12162.209456385306, 12336.876631233752, 21353.25263615766, 21695.0, 21716.565668686537, 21718.782812171678, 21746.347609571738, 8155.673773049011, 8382.87425688615, 8397.664093436317, 8442.240198382175, 8557.48655080697, 1109.3953795181646, 1120.2662256222188, 1122.3882983773863, 1141.4349896801136, 1179.7876382251106, 29634.55548166779, 29761.404771904872, 30789.69210307913, 30820.537691934638, 30844.383112338077, 1129.5538262386476, 1157.9983314432955, 1205.698575356161, 1227.895628871538, 1233.2908577567755, 13622.523211687543, 15697.843021569866, 15881.126538040287, 16185.352585896433, 16606.418775342805, 351060.0, 351238.0, 352125.0, 352148.0, 352872.0, 57350.13974790381, 57355.42644573501, 57702.0, 57722.711386442796, 57772.0, 1348.5886804524714, 1391.7077413743025, 1490.761478163467, 1490.8061951946177, 1596.9520914372588, 27659.723402765714, 27739.23456814631, 28406.431871362045, 30158.547621785252, 30196.396072077994, 43384.13231735411, 43617.56382436135, 44466.3330050043, 51090.48909511006, 52407.21389179089, 339666.0, 340176.0, 344602.0, 345150.0, 345545.0, 31269.374612508076, 31478.52782208223, 31643.20891977719, 31651.20871978219, 31663.683363166074, 39925.60074399335, 40171.39742903917, 41036.1792764149, 41055.0, 42093.36859947164, 37148.62851371452, 37353.066173345884, 38457.4231386535, 38501.80749096349, 38546.61453385543, 37011.81494092512, 38005.0, 38679.22641547097, 38860.805695971336, 39089.80455097706, 37438.8128059358, 37720.811395942845, 37751.05622359463, 37813.243735125696, 37843.62156378401, 32525.512117317787, 34073.0, 34098.147546310545, 34361.0, 34867.0, 26170.198982414488, 26177.869110654327, 26267.605985909846, 26386.0, 26651.0], "unit": "ms", "name": "ext.html", "value": 19807.477646862553}, {"lowerIsBetter": false, "replicates": [385.16995873891767, 387.20429517341853, 387.8429236159355, 387.90884142226554, 392.48584354495614, 14973.25133743314, 15776.290066946985, 16678.166091695428, 18335.724964125533, 18338.0, 14499.20254386011, 14618.41526338948, 15752.684946301068, 18267.634647307044, 18636.0, 6100.2679678438535, 6246.500279977591, 6251.531135164858, 6258.092576576394, 6258.342873998233, 4537.750423726694, 4653.371794807708, 6147.815565533041, 6698.56459330142, 6890.0, 6789.864202715943, 6811.829704257384, 6828.965855170742, 6867.450603951697, 6867.931320686804, 6964.616946067964, 6972.965135174291, 6995.930040699604, 7004.08946836913, 7027.332403421692, 6601.3728695773825, 6683.465322774215, 6778.593284402951, 6800.421964133055, 6806.965965170192, 24745.752542474525, 24752.25743227706, 25185.62221566678, 25200.495990080097, 25893.611595826078, 2605.3226161198227, 2605.452854900473, 2633.8814753336146, 2690.5695088785897, 2692.744189302013, 14689.77965330521, 14880.181590012546, 15385.923070384688, 15637.843621563867, 16128.516144515683, 2040.7142999980015, 2076.397844625057, 2098.821600163988, 2113.260358874397, 2141.550274442369, 73176.26823731748, 75222.0, 76819.6159019207, 77586.0, 77944.0, 16893.0, 16935.661286774197, 16944.830551694573, 16955.745663815054, 16978.06620635877, 26495.205143845717, 26529.33676658099, 27005.324866878484, 27292.181234562995, 27396.315092122855, 9847.950760246153, 9884.061014203638, 9884.159846413135, 9937.503124843728, 9989.250806189564, 8564.186402291773, 8564.914350856536, 8574.356923230782, 8605.741827745178, 8611.397202195787, 6955.312941799515, 8567.957160214159, 8587.313014958789, 8634.697785577475, 8661.090585488433, 1827.1412436154985, 1854.2212270846278, 1901.4485799118102, 1914.0716752375088, 1933.9129739161772, 830.5515021888112, 949.7578117580111, 988.357567581078, 1043.1602559939322, 1043.89561043895, 16025.519234422985, 16150.192490375433, 16173.919130404272, 16617.584560386087, 16728.0, 943.1888575824785, 989.4607438945792, 1031.3554028732042, 1035.6582327831798, 1037.1650821088945, 9255.67605133817, 9524.761880953032, 9559.235261179088, 9616.278779091595, 9806.901930980599, 599107.0, 620562.0, 625402.0, 628232.0, 631853.0, 80638.19361806424, 85770.5711471451, 88130.0, 88447.55776221078, 88757.55621221982, 1142.7485953090306, 1166.1894982986744, 1169.4678921090883, 1185.6265276437894, 1191.0352614382377, 16209.35162593483, 16232.107234101766, 16298.348066077224, 16500.009999399594, 16701.16494175262, 36972.26055478929, 37978.43032354572, 38685.0, 38792.80603596964, 38879.6112038876, 49651.0, 50070.0, 52285.215721764966, 55135.72432137813, 55903.0, 9155.313351498531, 9277.582508786985, 9339.53302334867, 9544.522773861141, 9574.617015319309, 11284.66146015588, 11299.887001129884, 11368.886311136783, 11394.544218231178, 16168.353265869704, 12707.428165732748, 13095.214287142802, 15160.0, 15220.315085821387, 15258.465953691568, 14896.478623248568, 14960.326785294905, 15144.848551514784, 15275.236238188269, 15526.22368881574, 15884.205789710237, 16072.116033618264, 16333.836661633231, 16397.59006024859, 16530.752038719187, 9682.70951871445, 9704.75738106553, 9753.609855605697, 9820.361676491006, 9849.65526206608, 14732.0, 15440.150791706565, 15572.766408503654, 15912.0, 16040.67918641644], "unit": "ms", "name": "jquery.html", "value": 10245.359311943035}, {"lowerIsBetter": false, "replicates": [1365.1808914651203, 1501.939922403104, 1509.1171664576216, 1817.9545511362219, 1818.2090790506127, 6707.798766037021, 7566.356859666928, 7637.427192960522, 7652.732154374598, 7665.5017423867475, 7721.343685786715, 7892.6448309826055, 7900.723474678387, 7902.644381002854, 7906.209379062091, 1917.300185432314, 1923.7306777051224, 2224.5217278285168, 2246.853954492961, 2286.9313920582326, 2290.530441259538, 2297.5060362022164, 2307.1232931486097, 2327.8952447139836, 2330.8484948478294, 2260.1863329201474, 2336.042222688698, 2336.100601268515, 2338.7427382987858, 2339.7543257957923, 2286.9085236590518, 2290.6220473621834, 2312.433453803829, 2320.860748355104, 2321.048370168223, 2297.9885100574556, 2308.111377119821, 2329.6621989811383, 2331.790138887491, 2344.4490544721975, 7664.693412263503, 7712.961435192844, 7723.266289702526, 7745.419093568005, 7751.379889608874, 4646.140464014168, 4712.929306060412, 4717.339572459859, 4734.786934587952, 4739.834105806315, 2933.574631678395, 2961.763058955278, 3011.834349110798, 3040.2247426905997, 3042.7261546460923, 917.3853518142861, 932.2681694869563, 934.0845970948509, 971.3152227679469, 991.1080027974808, 10592.523336449878, 10594.152467802634, 10601.310914790514, 10603.257771955967, 10614.787704245871, 4268.637165840938, 4289.378040184156, 4328.7402755834755, 4331.4152589400655, 4349.3475978603465, 9080.046595107418, 9089.22741566961, 9098.636054557743, 9118.81762364749, 9135.817283654289, 4769.95230047702, 4778.235482322882, 4802.111609352211, 4813.711177329372, 4814.15752243356, 4378.496472905656, 4388.034632380871, 4388.539203383662, 4416.933745993812, 4423.690341676116, 3344.41472742274, 3375.6793104655017, 3410.5225268462436, 3447.3450044491465, 3731.496248006484, 1209.5161935225804, 1229.65569640499, 1236.1532350340046, 1240.503798480615, 1249.188027781931, 1118.0608289037248, 1130.4912789244872, 1131.3890499130541, 1136.3068528197794, 1173.1553281637207, 7463.626818659045, 7471.3649339806725, 7522.059742532184, 7546.962265188639, 7551.093868735787, 1153.653903828848, 1164.574930150489, 1172.0, 1186.2526608236756, 1198.7722332756748, 5820.970895145582, 5867.882642347129, 5891.7054147292465, 5930.822075337746, 5932.2584676915385, 9315.25477961775, 9404.294677899185, 9495.43027418357, 9518.428894266366, 9520.809583808285, 9177.816443671089, 9227.169554740105, 9232.95383523078, 9338.859917101248, 9346.813063738824, 7235.782926512213, 7351.154617218981, 7361.668724907392, 7432.554046757212, 7472.252774722484, 7149.7140114396925, 7201.747938822116, 7243.855122897407, 7253.492255542062, 7255.56466612005, 8083.272505474535, 8167.959160204398, 8233.79415514593, 8295.460795048302, 8301.0, 8344.040435350013, 8438.409311348147, 8526.403151779316, 8529.74410767678, 8540.70107546233, 6949.30506949301, 7003.7548685795755, 7025.5082144251455, 7025.648717564203, 7032.753853615099, 6721.764738234138, 6869.931300686929, 7328.78013659591, 7579.317861392281, 7580.090389153116, 6810.0, 7183.712651493882, 7191.20896701352, 7192.640367981685, 7286.890696639661, 7099.964500177466, 7120.679569419285, 7161.57030578167, 7212.3148300911325, 7232.45756568249, 6944.826379340557, 6964.860702786017, 6996.440284777307, 7005.684744186422, 7127.679254433459, 6346.936530634634, 6355.237371515448, 6362.45919096882, 6389.87220255583, 6409.4231519163495, 6587.110740050223, 6588.143541339595, 6611.768588099393, 6622.966885165543, 6623.701933413105], "unit": "ms", "name": "mootools.html", "value": 4499.093873801822}, {"lowerIsBetter": false, "replicates": [357.8300307354007, 360.0315152240473, 365.66541614422806, 367.4616686554201, 373.2553555656464, 5964.850878728023, 6088.939110608892, 6103.664298463583, 6124.755009799603, 6159.414855588712, 5920.911186332209, 6079.7264123114455, 6116.908246376308, 6123.663198524079, 6127.908081378771, 4691.390119284505, 4731.408573928259, 4750.61995040396, 4759.666823322369, 4763.404574428197, 4380.904773806548, 4544.97738008947, 4611.769411529427, 4687.226607609758, 4723.881902952437, 4679.672422930396, 4695.389599352096, 4702.7883745231375, 4725.834595789148, 4730.0, 4652.95347046532, 4671.0, 4682.34447177392, 4702.553257440555, 4720.504347043579, 4142.668586513072, 4144.191882582898, 4152.563980782004, 4158.126793373365, 4158.854440094612, 5695.373508914016, 5789.594728369016, 5793.391693872167, 5810.389909059572, 5811.331696854873, 5525.861853453655, 5534.308211473566, 5535.806246781343, 5539.418361072069, 5546.251256080418, 6161.075838624198, 6459.8695228334855, 6460.030995350688, 6463.870722585522, 6467.191601049869, 4498.977505112486, 4513.638908887281, 4644.907101857978, 4657.138429390574, 4658.091672123937, 6703.597784132968, 6747.257801641816, 6749.325067493261, 6759.155105611848, 6762.898556521656, 4768.046390721869, 4772.427308722976, 4772.7136371817805, 4773.546513081252, 4779.091972525209, 4732.148213321635, 4829.010052939139, 4837.733924634179, 4848.7090774553635, 4859.635527335464, 5501.0, 5509.3664228614225, 5514.310711161105, 5517.972410137923, 5520.889582208413, 6543.0, 6545.541812073205, 6550.148480697479, 6553.508486863504, 6554.80335589933, 1701.4045084220425, 1706.5562953632145, 1708.5557754983793, 1709.9914500427667, 1711.1273250642262, 1111.660943412267, 1112.2770199370475, 1122.7810576937582, 1123.6572845282267, 1131.2646779593165, 6261.467775239064, 6305.432511074026, 6312.52656050798, 6324.810255692337, 6326.367363263729, 6652.933470665232, 6662.067310576524, 6666.366695163952, 6669.966650166718, 6675.7997260082275, 6316.715747791269, 6317.905231421532, 6319.3364696706185, 6320.71556779946, 6323.462505686976, 3577.266660334677, 3653.214558869856, 3671.2290419011774, 3684.2999830032745, 3686.5576130863933, 7020.613866237304, 7027.262137475594, 7029.5430796998035, 7031.507794454441, 7031.824204394931, 6869.278725733726, 6991.615461149584, 7000.229974702778, 7000.859982800315, 7006.614636195059, 7002.614856182959, 7004.614746188907, 7015.824604384931, 7017.7192912282935, 7020.368166865007, 6483.870322593616, 6668.566543174678, 6670.39966403026, 6801.455883529206, 6819.965900170666, 6697.497687673346, 6709.832254193489, 6719.059331693665, 6720.865582688221, 6739.292374300824, 6931.445484361138, 6976.511644184859, 6981.685824137824, 6982.755603553851, 6984.022236886942, 4624.930626040679, 4669.603083737785, 4704.623630109514, 4706.482286948364, 4723.480417154042, 6702.296258892746, 6806.18325800907, 6806.897896531655, 6807.115075040213, 6811.421029212374, 6588.571742836892, 6596.30738772422, 6598.736050558116, 6599.637019963948, 6610.3720146586, 6454.483641308778, 6566.540342176002, 6575.309592492719, 6588.868222635425, 6591.143151390289, 6483.222013358427, 6495.1556297681, 6500.609963402211, 6502.642354670538, 6509.837254068686, 6560.44236239925, 6626.635535045619, 6628.27089020217, 6638.535302528777, 6640.169978752656, 6349.8730025400155, 6433.099366088845, 6460.063290822898, 6462.709178086903, 6475.737231239863], "unit": "ms", "name": "prototype.html", "value": 4959.021258358917}, {"lowerIsBetter": false, "replicates": [760.0195747485743, 811.4319976016786, 890.2477406591428, 892.3664198419121, 917.0874979395503, 12361.876381236185, 15206.543803685894, 15500.922495387535, 15502.0, 15557.455489057871, 14657.193854338007, 14675.486357977474, 14738.115713057197, 14740.852591474082, 14752.483663071795, 757.2994979643835, 919.9632014719404, 923.4597760310227, 923.7228831350601, 925.1026504290827, 773.8684423647965, 901.6483571407157, 917.5228880981886, 917.8669092981547, 918.5590916360151, 959.0792838874688, 964.2864280432465, 969.0987381734984, 970.7379007667926, 971.5725080964388, 802.8434455281223, 810.7567729681072, 938.9248860091176, 961.9326647134703, 963.5808423335836, 702.4696354252558, 702.6451641920848, 704.503325155764, 705.717712914833, 706.4948561778302, 13502.0, 13540.729185416336, 13602.455901763917, 13623.795643065361, 13689.178649281075, 11571.884281157165, 11843.526258949632, 12086.456109475095, 12315.75368492625, 12370.75258494834, 10063.89936100637, 10228.0, 10241.231907606885, 10316.58733650653, 10424.426656533888, 3711.3690672585726, 3764.887053388403, 3768.886933391975, 3774.2640185163905, 3775.528058992626, 53449.73275133599, 53754.46245537534, 53866.461335386535, 54116.458835411926, 54132.188017179775, 9228.676996305097, 9255.76860578491, 9258.305627077996, 9291.628334866666, 9349.0, 19124.617507649768, 19157.0, 19176.0, 19179.61640767177, 19187.520311992033, 7258.564486130849, 7646.3882889368715, 7672.1560628330835, 7915.960420197862, 7948.205179482005, 4396.252637051709, 4465.0, 4465.955340446554, 4482.955170448319, 4505.549445055468, 3138.1370123215843, 3178.8410579470933, 3185.2355434695505, 3188.1232661018407, 3188.362327534456, 526.799816069891, 546.3389298948317, 548.6817645765447, 549.4176173256282, 559.152883381677, 633.2585390177013, 706.8268274272837, 723.3309189000154, 724.2359310926918, 733.479229746875, 9660.903390966141, 9985.950070249603, 10158.238132140119, 10317.896821031694, 10327.84508232377, 569.4789267819898, 724.5906063074347, 741.8862407947942, 744.129368638694, 750.0767912170695, 4334.19817333788, 4439.112177564435, 4501.819927202875, 4554.681172317906, 4594.563516465931, 60483.69758151181, 60821.0, 60979.39020609826, 61303.693481532304, 61508.0, 53484.0, 53978.19032714512, 54246.186307205426, 54553.45446545563, 54562.72718636461, 49202.26196607054, 49851.50148498541, 49944.25083623749, 50242.0, 50391.0, 9022.323325750463, 9158.725238242863, 9192.126747958933, 9307.860382094139, 9441.622335106793, 35936.82031589825, 36090.27819443649, 37093.0, 37670.0, 37733.4339984895, 41408.792956035024, 42671.0, 43027.3545896818, 47620.523794762994, 50098.749506252236, 11197.104231661284, 11666.650000499998, 11753.92631260923, 11797.88202117968, 11901.82147267809, 12754.043446741716, 12857.549985750455, 13208.0, 13713.725725485236, 14270.429182832571, 11772.587959421378, 11920.701982450508, 12173.87826121763, 12244.448999794853, 12708.046896482985, 11505.654830355103, 11584.652460426201, 11631.59289424866, 11644.592439264585, 11707.180497365103, 12173.2696038238, 12262.509499619915, 12599.307038112625, 12710.682232944251, 12783.552575659807, 10527.473626318806, 10791.082757965347, 10944.671659850217, 11161.0, 11224.214304998899, 10493.737656558647, 10571.94714026425, 10746.892531074589, 10780.568777248822, 10845.403502807421], "unit": "ms", "name": "yui.html", "value": 6715.037155768223}], "name": "dromaeo_css", "value": 7382.1732605477855}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 103.0, 110.0, 109.0, 110.0, 97.0, 109.0, 109.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 107.6}, {"lowerIsBetter": true, "replicates": [145.0, 128.0, 150.0, 156.0, 122.0, 147.0, 148.0, 122.0, 158.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.1}, {"lowerIsBetter": true, "replicates": [161.0, 159.0, 159.0, 159.0, 159.0, 161.0, 161.0, 163.0, 159.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [78.0, 71.0, 73.0, 143.0, 73.0, 73.0, 78.0, 71.0, 149.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.0}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 146.0, 93.0, 93.0, 92.0, 138.0, 92.0, 92.0, 138.0], "unit": "ms", "name": "audio-oscillator", "value": 107.0}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0, 108.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 57.0, 57.0, 57.0, 57.0, 60.0, 56.0, 61.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 57.0}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 65.0, 65.0, 66.0, 65.0, 64.0, 66.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.8}, {"lowerIsBetter": true, "replicates": [79.0, 79.0, 79.0, 81.0, 79.0, 81.0, 80.0, 81.0, 81.0, 81.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.1}, {"lowerIsBetter": true, "replicates": [124.0, 116.0, 123.0, 110.0, 109.0, 116.0, 113.0, 119.0, 112.0, 130.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.2}, {"lowerIsBetter": true, "replicates": [143.0, 134.0, 146.0, 143.0, 143.0, 152.0, 144.0, 144.0, 143.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.7}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 65.0, 63.0, 66.0, 63.0, 64.0, 64.0, 67.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.0}], "name": "kraken", "value": 1479.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.48072149594572], "unit": "score", "name": "Richards", "value": 213.32998600000002}, {"lowerIsBetter": false, "replicates": [173.40038148083926], "unit": "score", "name": "DeltaBlue", "value": 381.302506}, {"lowerIsBetter": false, "replicates": [279.79854504756577], "unit": "score", "name": "Encrypt", "value": 951.330894}, {"lowerIsBetter": false, "replicates": [5196.891191709845], "unit": "score", "name": "Decrypt", "value": 51.219275174476564}, {"lowerIsBetter": false, "replicates": [958.7727708533077], "unit": "score", "name": "RayTrace", "value": 771.8085270000001}, {"lowerIsBetter": false, "replicates": [559.2841163310962], "unit": "score", "name": "Earley", "value": 1191.6358439999997}, {"lowerIsBetter": false, "replicates": [11359.550561797752], "unit": "score", "name": "Boyer", "value": 58.669838773491605}, {"lowerIsBetter": false, "replicates": [24261.904761904763], "unit": "score", "name": "RegExp", "value": 37.547958783120734}, {"lowerIsBetter": false, "replicates": [523.5602094240837], "unit": "score", "name": "Splay", "value": 155.64781000000008}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 22913.10500162061}]} 09:25:12 INFO - RETURN: dromaeo_css: 7382.17 09:25:12 INFO - RETURN: kraken: 1479.80 09:25:12 INFO - RETURN: v8_7: 22913.11 09:25:12 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "22913.11"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1479.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7382.17"}}} 09:25:12 INFO - Return code: 0 09:25:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:25:12 INFO - # TBPL SUCCESS # 09:25:12 INFO - Running post-action listener: _resource_record_post_action 09:25:12 INFO - Running post-run listener: _resource_record_post_run 09:25:13 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 12288; Write bytes: 519159808; Read time: 196; Write time: 486108 09:25:13 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 253046784; Read time: 180; Write time: 269352 09:25:13 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 8192; Write bytes: 266113024; Read time: 16; Write time: 216756 09:25:13 INFO - Running post-run listener: _upload_blobber_files 09:25:13 INFO - Blob upload gear active. 09:25:13 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:25:13 INFO - Copying logs to upload dir... 09:25:13 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1260.120791 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-01-23 09:25:13.589876) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-23 09:25:13.593633) ========= 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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010066 build_url: 'https://queue.taskcluster.net/v1/task/kVETwNojQkCuPfXSpJCHmg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-23 09:25:13.649290) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:25:13.649619) ========= 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=3a3620fb28aea1604ab609ef000001d2-1453567661.988718-657960524 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-23 09:25:13.698949) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-23 09:25:13.699274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-23 09:25:13.699568) ========= ========= Total master_lag: 0.27 =========