builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1457258349.22 results: success (0) buildid: 20160305031719 builduid: 77ad713622c74f5a93e694d0d647c68b revision: 78fe98c670fcc9a1548ac655ae9a488d940fd9c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.219110) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.219523) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.219804) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004421 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.236849) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.237124) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.246503) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.246762) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004123 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.294707) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.295013) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.295364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.295628) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-06 01:59:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-03-06 01:59:09 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162129 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.469764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.470082) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014940 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:09.519494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-06 01:59:09.519825) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 78fe98c670fcc9a1548ac655ae9a488d940fd9c8 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 78fe98c670fcc9a1548ac655ae9a488d940fd9c8 --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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-06 01:59:09,759 truncating revision to first 12 chars 2016-03-06 01:59:09,759 Setting DEBUG logging. 2016-03-06 01:59:09,759 attempt 1/10 2016-03-06 01:59:09,759 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/78fe98c670fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-06 01:59:10,845 unpacking tar archive at: mozilla-beta-78fe98c670fc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.401656 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-06 01:59:10.936969) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:10.937319) ========= echo 78fe98c670fcc9a1548ac655ae9a488d940fd9c8 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'78fe98c670fcc9a1548ac655ae9a488d940fd9c8'] 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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 78fe98c670fcc9a1548ac655ae9a488d940fd9c8 program finished with exit code 0 elapsedTime=0.003896 script_repo_revision: '78fe98c670fcc9a1548ac655ae9a488d940fd9c8' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:10.985515) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:10.985808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-06 01:59:10.995356) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-03-06 01:59:10.995621) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:59:11 INFO - MultiFileLogger online at 20160306 01:59:11 in /builds/slave/test-pgo 01:59:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:59:11 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 01:59:11 INFO - {'append_to_log': False, 01:59:11 INFO - 'base_work_dir': '/builds/slave/test-pgo', 01:59:11 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:59:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 01:59:11 INFO - 'branch': 'Mozilla-Beta', 01:59:11 INFO - 'buildbot_json_path': 'buildprops.json', 01:59:11 INFO - 'config_files': ('talos/linux_config.py',), 01:59:11 INFO - 'default_actions': ('clobber', 01:59:11 INFO - 'read-buildbot-config', 01:59:11 INFO - 'download-and-extract', 01:59:11 INFO - 'populate-webroot', 01:59:11 INFO - 'create-virtualenv', 01:59:11 INFO - 'install', 01:59:11 INFO - 'run-tests'), 01:59:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:59:11 INFO - 'download_minidump_stackwalk': True, 01:59:11 INFO - 'download_symbols': 'ondemand', 01:59:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:59:11 INFO - 'tooltool.py': '/tools/tooltool.py', 01:59:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:59:11 INFO - '/tools/misc-python/virtualenv.py')}, 01:59:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:59:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:59:11 INFO - 'installer_path': 'installer.exe', 01:59:11 INFO - 'log_level': 'info', 01:59:11 INFO - 'log_name': 'talos', 01:59:11 INFO - 'log_to_console': True, 01:59:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:59:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:59:11 INFO - 'opt_config_files': (), 01:59:11 INFO - 'pip_index': False, 01:59:11 INFO - 'sps_profile': False, 01:59:11 INFO - 'sps_profile_interval': 0, 01:59:11 INFO - 'suite': 'dromaeojs', 01:59:11 INFO - 'system_bits': '32', 01:59:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:59:11 INFO - 'title': 'talos-linux64-ix-013', 01:59:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:59:11 INFO - 'use_talos_json': True, 01:59:11 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 01:59:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:59:11 INFO - 'work_dir': 'build'} 01:59:11 INFO - ##### 01:59:11 INFO - ##### Running clobber step. 01:59:11 INFO - ##### 01:59:11 INFO - Running pre-action listener: _resource_record_pre_action 01:59:11 INFO - Running main action method: clobber 01:59:11 INFO - rmtree: /builds/slave/test-pgo/build 01:59:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 01:59:11 INFO - Running post-action listener: _resource_record_post_action 01:59:11 INFO - ##### 01:59:11 INFO - ##### Running read-buildbot-config step. 01:59:11 INFO - ##### 01:59:11 INFO - Running pre-action listener: _resource_record_pre_action 01:59:11 INFO - Running main action method: read_buildbot_config 01:59:11 INFO - Using buildbot properties: 01:59:11 INFO - { 01:59:11 INFO - "properties": { 01:59:11 INFO - "buildnumber": 131, 01:59:11 INFO - "product": "firefox", 01:59:11 INFO - "script_repo_revision": "production", 01:59:11 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:59:11 INFO - "repository": "", 01:59:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 01:59:11 INFO - "buildid": "20160305031719", 01:59:11 INFO - "slavename": "talos-linux64-ix-013", 01:59:11 INFO - "pgo_build": "True", 01:59:11 INFO - "basedir": "/builds/slave/test-pgo", 01:59:11 INFO - "project": "", 01:59:11 INFO - "platform": "ubuntu64_hw", 01:59:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:59:11 INFO - "slavebuilddir": "test", 01:59:11 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:59:11 INFO - "branch": "mozilla-beta", 01:59:11 INFO - "repo_path": "releases/mozilla-beta", 01:59:11 INFO - "stage_platform": "linux64-pgo", 01:59:11 INFO - "builduid": "77ad713622c74f5a93e694d0d647c68b", 01:59:11 INFO - "revision": "78fe98c670fcc9a1548ac655ae9a488d940fd9c8" 01:59:11 INFO - }, 01:59:11 INFO - "sourcestamp": { 01:59:11 INFO - "repository": "", 01:59:11 INFO - "hasPatch": false, 01:59:11 INFO - "project": "", 01:59:11 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:59:11 INFO - "changes": [ 01:59:11 INFO - { 01:59:11 INFO - "category": null, 01:59:11 INFO - "files": [ 01:59:11 INFO - { 01:59:11 INFO - "url": null, 01:59:11 INFO - "name": "https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2" 01:59:11 INFO - } 01:59:11 INFO - ], 01:59:11 INFO - "repository": "", 01:59:11 INFO - "rev": "78fe98c670fcc9a1548ac655ae9a488d940fd9c8", 01:59:11 INFO - "who": "ffxbld", 01:59:11 INFO - "when": 1457185072, 01:59:11 INFO - "number": 7697725, 01:59:11 INFO - "comments": "No bug, Automated blocklist update from host bld-linux64-spot-470 - a=blocklist-update", 01:59:11 INFO - "project": "", 01:59:11 INFO - "at": "Sat 05 Mar 2016 05:37:52", 01:59:11 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:59:11 INFO - "revlink": "", 01:59:11 INFO - "properties": [ 01:59:11 INFO - [ 01:59:11 INFO - "buildid", 01:59:11 INFO - "20160305031719", 01:59:11 INFO - "Change" 01:59:11 INFO - ], 01:59:11 INFO - [ 01:59:11 INFO - "builduid", 01:59:11 INFO - "77ad713622c74f5a93e694d0d647c68b", 01:59:11 INFO - "Change" 01:59:11 INFO - ], 01:59:11 INFO - [ 01:59:11 INFO - "pgo_build", 01:59:11 INFO - "True", 01:59:11 INFO - "Change" 01:59:11 INFO - ] 01:59:11 INFO - ], 01:59:11 INFO - "revision": "78fe98c670fcc9a1548ac655ae9a488d940fd9c8" 01:59:11 INFO - } 01:59:11 INFO - ], 01:59:11 INFO - "revision": "78fe98c670fcc9a1548ac655ae9a488d940fd9c8" 01:59:11 INFO - } 01:59:11 INFO - } 01:59:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2. 01:59:11 INFO - Running post-action listener: _resource_record_post_action 01:59:11 INFO - ##### 01:59:11 INFO - ##### Running download-and-extract step. 01:59:11 INFO - ##### 01:59:11 INFO - Running pre-action listener: _resource_record_pre_action 01:59:11 INFO - Running main action method: download_and_extract 01:59:11 INFO - mkdir: /builds/slave/test-pgo/build/tests 01:59:11 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')]} 01:59:11 INFO - https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 01:59:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/test_packages.json 01:59:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/test_packages.json 01:59:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 01:59:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 01:59:12 INFO - Downloaded 1270 bytes. 01:59:12 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 01:59:12 INFO - Using the following test package requirements: 01:59:12 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:59:12 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:59:12 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:59:12 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:59:12 INFO - u'jsshell-linux-x86_64.zip'], 01:59:12 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:59:12 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:59:12 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:59:12 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:59:12 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 01:59:12 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:59:12 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 01:59:12 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:59:12 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:59:12 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:59:12 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:59:12 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:59:12 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:12 INFO - https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:59:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:59:13 INFO - Downloaded 21584474 bytes. 01:59:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:59:13 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:13 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:59:14 INFO - Return code: 0 01:59:14 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:59: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')]} 01:59:14 INFO - https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:59:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:59:15 INFO - Downloaded 21584474 bytes. 01:59:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:59:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:59:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:59:16 INFO - Return code: 0 01:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:16 INFO - https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:59:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:59:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:59:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:59:17 INFO - Downloaded 11132038 bytes. 01:59:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 01:59:17 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:59:17 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 01:59:17 INFO - Return code: 0 01:59: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')]} 01:59:17 INFO - https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:59:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:59:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:59:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:59:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:59:19 INFO - Downloaded 57946057 bytes. 01:59:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:59:19 INFO - mkdir: /builds/slave/test-pgo/properties 01:59:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 01:59:19 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 01:59:19 INFO - Contents: 01:59:19 INFO - build_url:https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:59:20 INFO - Running post-action listener: _resource_record_post_action 01:59:20 INFO - Running post-action listener: set_extra_try_arguments 01:59:20 INFO - ##### 01:59:20 INFO - ##### Running populate-webroot step. 01:59:20 INFO - ##### 01:59:20 INFO - Running pre-action listener: _resource_record_pre_action 01:59:20 INFO - Running main action method: populate_webroot 01:59:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:59:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:59:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:59:20 INFO - '--tppagecycles', 01:59:20 INFO - '7'], 01:59:20 INFO - 'tests': ['tsvgm']}}, 01:59:20 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:59:20 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:59:20 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:59:20 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:59:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:59:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:59:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'tests': ['damp', 'tps']}, 01:59:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['damp', 'tps']}, 01:59:20 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:59:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:59:20 INFO - 'other': {'tests': ['a11yr', 01:59:20 INFO - 'ts_paint', 01:59:20 INFO - 'tpaint', 01:59:20 INFO - 'sessionrestore', 01:59:20 INFO - 'sessionrestore_no_auto_restore']}, 01:59:20 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['a11yr', 01:59:20 INFO - 'ts_paint', 01:59:20 INFO - 'tpaint', 01:59:20 INFO - 'sessionrestore', 01:59:20 INFO - 'sessionrestore_no_auto_restore']}, 01:59:20 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['a11yr', 01:59:20 INFO - 'ts_paint', 01:59:20 INFO - 'tpaint', 01:59:20 INFO - 'sessionrestore', 01:59:20 INFO - 'sessionrestore_no_auto_restore']}, 01:59:20 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['a11yr', 01:59:20 INFO - 'ts_paint', 01:59:20 INFO - 'tpaint', 01:59:20 INFO - 'sessionrestore', 01:59:20 INFO - 'sessionrestore_no_auto_restore']}, 01:59:20 INFO - 'other_l64': {'tests': ['a11yr', 01:59:20 INFO - 'ts_paint', 01:59:20 INFO - 'tpaint', 01:59:20 INFO - 'sessionrestore', 01:59:20 INFO - 'sessionrestore_no_auto_restore']}, 01:59:20 INFO - 'other_nol64': {'tests': ['a11yr', 01:59:20 INFO - 'ts_paint', 01:59:20 INFO - 'tpaint', 01:59:20 INFO - 'sessionrestore', 01:59:20 INFO - 'sessionrestore_no_auto_restore']}, 01:59:20 INFO - 'svgr': {'tests': ['tsvgx', 01:59:20 INFO - 'tsvgr_opacity', 01:59:20 INFO - 'tart', 01:59:20 INFO - 'tscrollx', 01:59:20 INFO - 'cart']}, 01:59:20 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['tsvgx', 01:59:20 INFO - 'tsvgr_opacity', 01:59:20 INFO - 'tart', 01:59:20 INFO - 'tscrollx', 01:59:20 INFO - 'cart']}, 01:59:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'tests': ['tp5o']}, 01:59:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'talos_options': ['--e10s'], 01:59:20 INFO - 'tests': ['tp5o']}, 01:59:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'talos_options': ['--xperf_path', 01:59:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:59:20 INFO - 'tests': ['tp5n']}, 01:59:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:59:20 INFO - 'talos_options': ['--e10s', 01:59:20 INFO - '--xperf_path', 01:59:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:59:20 INFO - 'tests': ['tp5n']}}, 01:59:20 INFO - 'talos.zip': {'path': '', 01:59:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:59:20 INFO - Running post-action listener: _resource_record_post_action 01:59:20 INFO - ##### 01:59:20 INFO - ##### Running create-virtualenv step. 01:59:20 INFO - ##### 01:59:20 INFO - Running pre-action listener: _resource_record_pre_action 01:59:20 INFO - Running main action method: create_virtualenv 01:59:20 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 01:59:20 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 01:59:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 01:59:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:59:20 INFO - Using real prefix '/usr' 01:59:20 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 01:59:20 INFO - Installing distribute.............................................................................................................................................................................................done. 01:59:22 INFO - Installing pip.................done. 01:59:22 INFO - Return code: 0 01:59:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 01:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:22 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:22 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 01:59:22 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 01:59:22 INFO - Using env: {'DISPLAY': ':0', 01:59:22 INFO - 'HOME': '/home/cltbld', 01:59:22 INFO - 'LANG': 'en_US.UTF-8', 01:59:22 INFO - 'LANGUAGE': 'en_US:en', 01:59:22 INFO - 'LOGNAME': 'cltbld', 01:59:22 INFO - 'MAIL': '/var/mail/cltbld', 01:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:22 INFO - 'MOZ_NO_REMOTE': '1', 01:59:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:22 INFO - 'NO_EM_RESTART': '1', 01:59:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:22 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:22 INFO - 'SHELL': '/bin/bash', 01:59:22 INFO - 'SHLVL': '1', 01:59:22 INFO - 'TERM': 'linux', 01:59:22 INFO - 'TMOUT': '86400', 01:59:22 INFO - 'USER': 'cltbld', 01:59:22 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:22 INFO - '_': '/tools/buildbot/bin/python'} 01:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:22 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 01:59:22 INFO - Cleaning up... 01:59:22 INFO - Return code: 0 01:59:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 01:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:22 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:22 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 01:59:22 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 01:59:22 INFO - Using env: {'DISPLAY': ':0', 01:59:22 INFO - 'HOME': '/home/cltbld', 01:59:22 INFO - 'LANG': 'en_US.UTF-8', 01:59:22 INFO - 'LANGUAGE': 'en_US:en', 01:59:22 INFO - 'LOGNAME': 'cltbld', 01:59:22 INFO - 'MAIL': '/var/mail/cltbld', 01:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:22 INFO - 'MOZ_NO_REMOTE': '1', 01:59:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:22 INFO - 'NO_EM_RESTART': '1', 01:59:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:22 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:22 INFO - 'SHELL': '/bin/bash', 01:59:22 INFO - 'SHLVL': '1', 01:59:22 INFO - 'TERM': 'linux', 01:59:22 INFO - 'TMOUT': '86400', 01:59:22 INFO - 'USER': 'cltbld', 01:59:22 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:22 INFO - '_': '/tools/buildbot/bin/python'} 01:59:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:23 INFO - Downloading/unpacking psutil>=0.7.1 01:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 01:59:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:59:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:59:25 INFO - Installing collected packages: psutil 01:59:25 INFO - Running setup.py install for psutil 01:59:25 INFO - building 'psutil._psutil_linux' extension 01:59:25 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 01:59:25 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 01:59:25 INFO - building 'psutil._psutil_posix' extension 01:59:25 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 01:59:25 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 01:59:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:59:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:59:25 INFO - Successfully installed psutil 01:59:25 INFO - Cleaning up... 01:59:25 INFO - Return code: 0 01:59:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 01:59:25 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')]} 01:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:25 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')]} 01:59:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:25 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:25 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 01:59:25 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 01:59:25 INFO - Using env: {'DISPLAY': ':0', 01:59:25 INFO - 'HOME': '/home/cltbld', 01:59:25 INFO - 'LANG': 'en_US.UTF-8', 01:59:25 INFO - 'LANGUAGE': 'en_US:en', 01:59:25 INFO - 'LOGNAME': 'cltbld', 01:59:25 INFO - 'MAIL': '/var/mail/cltbld', 01:59:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:25 INFO - 'MOZ_NO_REMOTE': '1', 01:59:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:25 INFO - 'NO_EM_RESTART': '1', 01:59:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:25 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:25 INFO - 'SHELL': '/bin/bash', 01:59:25 INFO - 'SHLVL': '1', 01:59:25 INFO - 'TERM': 'linux', 01:59:25 INFO - 'TMOUT': '86400', 01:59:25 INFO - 'USER': 'cltbld', 01:59:25 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:25 INFO - '_': '/tools/buildbot/bin/python'} 01:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:25 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:59:25 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 01:59:25 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 01:59:25 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 01:59:25 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 01:59:25 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 01:59:25 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 01:59:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:59:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:59:27 INFO - Installing collected packages: mozsystemmonitor 01:59:27 INFO - Running setup.py install for mozsystemmonitor 01:59:28 INFO - Successfully installed mozsystemmonitor 01:59:28 INFO - Cleaning up... 01:59:28 INFO - Return code: 0 01:59:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 01:59: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')]} 01:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59: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')]} 01:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59: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', '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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 01:59: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 blobuploader==1.2.4 01:59:28 INFO - Using env: {'DISPLAY': ':0', 01:59:28 INFO - 'HOME': '/home/cltbld', 01:59:28 INFO - 'LANG': 'en_US.UTF-8', 01:59:28 INFO - 'LANGUAGE': 'en_US:en', 01:59:28 INFO - 'LOGNAME': 'cltbld', 01:59:28 INFO - 'MAIL': '/var/mail/cltbld', 01:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:28 INFO - 'MOZ_NO_REMOTE': '1', 01:59:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:28 INFO - 'NO_EM_RESTART': '1', 01:59:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:28 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:28 INFO - 'SHELL': '/bin/bash', 01:59:28 INFO - 'SHLVL': '1', 01:59:28 INFO - 'TERM': 'linux', 01:59:28 INFO - 'TMOUT': '86400', 01:59:28 INFO - 'USER': 'cltbld', 01:59:28 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:28 INFO - '_': '/tools/buildbot/bin/python'} 01:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:28 INFO - Downloading/unpacking blobuploader==1.2.4 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:30 INFO - Downloading blobuploader-1.2.4.tar.gz 01:59:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:59:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 01:59:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 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 01:59:30 INFO - Downloading docopt-0.6.1.tar.gz 01:59:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 01:59:30 INFO - Installing collected packages: blobuploader, requests, docopt 01:59:30 INFO - Running setup.py install for blobuploader 01:59:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:59:30 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 01:59:30 INFO - Running setup.py install for requests 01:59:31 INFO - Running setup.py install for docopt 01:59:31 INFO - Successfully installed blobuploader requests docopt 01:59:31 INFO - Cleaning up... 01:59:31 INFO - Return code: 0 01:59:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:59: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')]} 01:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59: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')]} 01:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:31 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:31 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 01:59:31 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 01:59:31 INFO - Using env: {'DISPLAY': ':0', 01:59:31 INFO - 'HOME': '/home/cltbld', 01:59:31 INFO - 'LANG': 'en_US.UTF-8', 01:59:31 INFO - 'LANGUAGE': 'en_US:en', 01:59:31 INFO - 'LOGNAME': 'cltbld', 01:59:31 INFO - 'MAIL': '/var/mail/cltbld', 01:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:31 INFO - 'MOZ_NO_REMOTE': '1', 01:59:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:31 INFO - 'NO_EM_RESTART': '1', 01:59:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:31 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:31 INFO - 'SHELL': '/bin/bash', 01:59:31 INFO - 'SHLVL': '1', 01:59:31 INFO - 'TERM': 'linux', 01:59:31 INFO - 'TMOUT': '86400', 01:59:31 INFO - 'USER': 'cltbld', 01:59:31 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:31 INFO - '_': '/tools/buildbot/bin/python'} 01:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:59:31 INFO - Running setup.py (path:/tmp/pip-cHv1l7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:59:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:59:31 INFO - Running setup.py (path:/tmp/pip-p9ynQf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:59:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:59:31 INFO - Running setup.py (path:/tmp/pip-7Ys2Rm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:59:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:59:31 INFO - Running setup.py (path:/tmp/pip-zJ9O_k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:59:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:59:32 INFO - Running setup.py (path:/tmp/pip-1qJqnh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:59:32 INFO - Running setup.py (path:/tmp/pip-Sm_Ynf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:59:32 INFO - Running setup.py (path:/tmp/pip-e7bH2o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:59:32 INFO - Running setup.py (path:/tmp/pip-4NxzEi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:59:32 INFO - Running setup.py (path:/tmp/pip-PrSmna-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:59:32 INFO - Running setup.py (path:/tmp/pip-hCo_qc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:59:32 INFO - Running setup.py (path:/tmp/pip-5tanFV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:59:32 INFO - Running setup.py (path:/tmp/pip-dZCUXl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:59:32 INFO - Running setup.py (path:/tmp/pip-k7Fx4b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:59:32 INFO - Running setup.py (path:/tmp/pip-7seK28-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:59:32 INFO - Running setup.py (path:/tmp/pip-c8CXza-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:59:32 INFO - Running setup.py (path:/tmp/pip-wuc9i3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:59:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:59:32 INFO - Running setup.py (path:/tmp/pip-RyzWkp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:59:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:59:32 INFO - Running setup.py install for manifestparser 01:59:33 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Running setup.py install for mozcrash 01:59:33 INFO - Running setup.py install for mozdebug 01:59:33 INFO - Running setup.py install for mozdevice 01:59:33 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Running setup.py install for mozfile 01:59:33 INFO - Running setup.py install for mozhttpd 01:59:33 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Running setup.py install for mozinfo 01:59:33 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Running setup.py install for mozInstall 01:59:33 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Running setup.py install for mozleak 01:59:33 INFO - Running setup.py install for mozlog 01:59:33 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 01:59:33 INFO - Running setup.py install for moznetwork 01:59:34 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 01:59:34 INFO - Running setup.py install for mozprocess 01:59:34 INFO - Running setup.py install for mozprofile 01:59:34 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 01:59:34 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 01:59:34 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 01:59:34 INFO - Running setup.py install for mozrunner 01:59:34 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 01:59:34 INFO - Running setup.py install for mozscreenshot 01:59:34 INFO - Running setup.py install for moztest 01:59:34 INFO - Running setup.py install for mozversion 01:59:34 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 01:59:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:59:34 INFO - Cleaning up... 01:59:34 INFO - Return code: 0 01:59:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:34 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:34 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 01:59:34 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 01:59:34 INFO - Using env: {'DISPLAY': ':0', 01:59:34 INFO - 'HOME': '/home/cltbld', 01:59:34 INFO - 'LANG': 'en_US.UTF-8', 01:59:34 INFO - 'LANGUAGE': 'en_US:en', 01:59:34 INFO - 'LOGNAME': 'cltbld', 01:59:34 INFO - 'MAIL': '/var/mail/cltbld', 01:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:34 INFO - 'MOZ_NO_REMOTE': '1', 01:59:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:34 INFO - 'NO_EM_RESTART': '1', 01:59:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:34 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:34 INFO - 'SHELL': '/bin/bash', 01:59:34 INFO - 'SHLVL': '1', 01:59:34 INFO - 'TERM': 'linux', 01:59:34 INFO - 'TMOUT': '86400', 01:59:34 INFO - 'USER': 'cltbld', 01:59:34 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:34 INFO - '_': '/tools/buildbot/bin/python'} 01:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:59:34 INFO - Running setup.py (path:/tmp/pip-c_0xuk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:59:35 INFO - Running setup.py (path:/tmp/pip-LlyzqN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:59:35 INFO - Running setup.py (path:/tmp/pip-eoFVRG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:59:35 INFO - Running setup.py (path:/tmp/pip-FdwiKJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:59:35 INFO - Running setup.py (path:/tmp/pip-iqp2DX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:59:35 INFO - Running setup.py (path:/tmp/pip-ePWXg1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:59:35 INFO - Running setup.py (path:/tmp/pip-WXWCg2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:59:35 INFO - Running setup.py (path:/tmp/pip-3VBxaC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:59:35 INFO - Running setup.py (path:/tmp/pip-Iwe2cZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:59:35 INFO - Running setup.py (path:/tmp/pip-51pLdm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:59:35 INFO - Running setup.py (path:/tmp/pip-KTCa3R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:59:35 INFO - Running setup.py (path:/tmp/pip-SxFp9F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:59:35 INFO - Running setup.py (path:/tmp/pip-5GsDHF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:59:35 INFO - Running setup.py (path:/tmp/pip-gEx1Ca-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:59:35 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)) 01:59:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:59:35 INFO - Running setup.py (path:/tmp/pip-s3Hw20-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:59:36 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)) 01:59:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:59:36 INFO - Running setup.py (path:/tmp/pip-DU7trx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:59:36 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)) 01:59:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:59:36 INFO - Running setup.py (path:/tmp/pip-2WaOOX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:59:36 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)) 01:59:36 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)) 01:59:36 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)) 01:59:36 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)) 01:59:36 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)) 01:59:36 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)) 01:59:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:38 INFO - Downloading blessings-1.6.tar.gz 01:59:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 01:59:38 INFO - Installing collected packages: blessings 01:59:38 INFO - Running setup.py install for blessings 01:59:38 INFO - Successfully installed blessings 01:59:38 INFO - Cleaning up... 01:59:38 INFO - Return code: 0 01:59:38 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 01:59:38 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:59:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:59:38 INFO - Reading from file tmpfile_stdout 01:59:38 INFO - Current package versions: 01:59:38 INFO - argparse == 1.2.1 01:59:38 INFO - blessings == 1.6 01:59:38 INFO - blobuploader == 1.2.4 01:59:38 INFO - docopt == 0.6.1 01:59:38 INFO - manifestparser == 1.1 01:59:38 INFO - mozInstall == 1.12 01:59:38 INFO - mozcrash == 0.16 01:59:38 INFO - mozdebug == 0.1 01:59:38 INFO - mozdevice == 0.47 01:59:38 INFO - mozfile == 1.2 01:59:38 INFO - mozhttpd == 0.7 01:59:38 INFO - mozinfo == 0.9 01:59:38 INFO - mozleak == 0.1 01:59:38 INFO - mozlog == 3.1 01:59:38 INFO - moznetwork == 0.27 01:59:38 INFO - mozprocess == 0.22 01:59:38 INFO - mozprofile == 0.27 01:59:38 INFO - mozrunner == 6.11 01:59:38 INFO - mozscreenshot == 0.1 01:59:38 INFO - mozsystemmonitor == 0.0 01:59:38 INFO - moztest == 0.7 01:59:38 INFO - mozversion == 1.4 01:59:38 INFO - psutil == 3.1.1 01:59:38 INFO - requests == 1.2.3 01:59:38 INFO - wsgiref == 0.1.2 01:59:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:38 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:38 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 01:59:38 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 01:59:38 INFO - Using env: {'DISPLAY': ':0', 01:59:38 INFO - 'HOME': '/home/cltbld', 01:59:38 INFO - 'LANG': 'en_US.UTF-8', 01:59:38 INFO - 'LANGUAGE': 'en_US:en', 01:59:38 INFO - 'LOGNAME': 'cltbld', 01:59:38 INFO - 'MAIL': '/var/mail/cltbld', 01:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:38 INFO - 'MOZ_NO_REMOTE': '1', 01:59:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:38 INFO - 'NO_EM_RESTART': '1', 01:59:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:38 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:38 INFO - 'SHELL': '/bin/bash', 01:59:38 INFO - 'SHLVL': '1', 01:59:38 INFO - 'TERM': 'linux', 01:59:38 INFO - 'TMOUT': '86400', 01:59:38 INFO - 'USER': 'cltbld', 01:59:38 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:38 INFO - '_': '/tools/buildbot/bin/python'} 01:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 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)) 01:59:38 INFO - Cleaning up... 01:59:38 INFO - Return code: 0 01:59:38 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 01:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:38 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:38 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 01:59:38 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 01:59:38 INFO - Using env: {'DISPLAY': ':0', 01:59:38 INFO - 'HOME': '/home/cltbld', 01:59:38 INFO - 'LANG': 'en_US.UTF-8', 01:59:38 INFO - 'LANGUAGE': 'en_US:en', 01:59:38 INFO - 'LOGNAME': 'cltbld', 01:59:38 INFO - 'MAIL': '/var/mail/cltbld', 01:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:38 INFO - 'MOZ_NO_REMOTE': '1', 01:59:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:38 INFO - 'NO_EM_RESTART': '1', 01:59:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:38 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:38 INFO - 'SHELL': '/bin/bash', 01:59:38 INFO - 'SHLVL': '1', 01:59:38 INFO - 'TERM': 'linux', 01:59:38 INFO - 'TMOUT': '86400', 01:59:38 INFO - 'USER': 'cltbld', 01:59:38 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:38 INFO - '_': '/tools/buildbot/bin/python'} 01:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:38 INFO - Downloading/unpacking jsonschema 01:59:38 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 01:59:38 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 01:59:38 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 01:59:38 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 01:59:38 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 01:59:38 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 01:59:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:59:40 INFO - Downloading/unpacking functools32 (from jsonschema) 01:59:40 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 01:59:40 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 01:59:40 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 01:59:40 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 01:59:40 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 01:59:40 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 01:59:41 INFO - Downloading functools32-3.2.3-2.tar.gz 01:59:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 01:59:41 INFO - warning: no files found matching '*.txt' 01:59:41 INFO - no previously-included directories found matching 'build' 01:59:41 INFO - no previously-included directories found matching 'dist' 01:59:41 INFO - no previously-included directories found matching '.git*' 01:59:41 INFO - Installing collected packages: jsonschema, functools32 01:59:41 INFO - Running setup.py install for functools32 01:59:41 INFO - warning: no files found matching '*.txt' 01:59:41 INFO - no previously-included directories found matching 'build' 01:59:41 INFO - no previously-included directories found matching 'dist' 01:59:41 INFO - no previously-included directories found matching '.git*' 01:59:41 INFO - Successfully installed jsonschema functools32 01:59:41 INFO - Cleaning up... 01:59:41 INFO - Return code: 0 01:59:41 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 01:59:41 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')]} 01:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:41 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')]} 01:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:41 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 0x7fe95c0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', '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 01:59:41 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 01:59:41 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 01:59:41 INFO - Using env: {'DISPLAY': ':0', 01:59:41 INFO - 'HOME': '/home/cltbld', 01:59:41 INFO - 'LANG': 'en_US.UTF-8', 01:59:41 INFO - 'LANGUAGE': 'en_US:en', 01:59:41 INFO - 'LOGNAME': 'cltbld', 01:59:41 INFO - 'MAIL': '/var/mail/cltbld', 01:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:41 INFO - 'MOZ_NO_REMOTE': '1', 01:59:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:59:41 INFO - 'NO_EM_RESTART': '1', 01:59:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:59:41 INFO - 'PWD': '/builds/slave/test-pgo', 01:59:41 INFO - 'SHELL': '/bin/bash', 01:59:41 INFO - 'SHLVL': '1', 01:59:41 INFO - 'TERM': 'linux', 01:59:41 INFO - 'TMOUT': '86400', 01:59:41 INFO - 'USER': 'cltbld', 01:59:41 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 01:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:41 INFO - '_': '/tools/buildbot/bin/python'} 01:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:41 INFO - Downloading/unpacking flake8 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:59:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:59:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59: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 01:59:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 01:59:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:59:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:59:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:59:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:59:44 INFO - no previously-included directories found matching 'docs/_build' 01:59:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:59:44 INFO - Running setup.py install for pep8 01:59:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:59:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:59:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:59:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:59:44 INFO - no previously-included directories found matching 'docs/_build' 01:59:44 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 01:59:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:59:44 INFO - Cleaning up... 01:59:44 INFO - Return code: 0 01:59:44 INFO - Running post-action listener: _resource_record_post_action 01:59:44 INFO - Running post-action listener: _start_resource_monitoring 01:59:44 INFO - Starting resource monitoring. 01:59:44 INFO - ##### 01:59:44 INFO - ##### Running install step. 01:59:44 INFO - ##### 01:59:44 INFO - Running pre-action listener: _resource_record_pre_action 01:59:44 INFO - Running main action method: install 01:59:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:59:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:59:44 INFO - Reading from file tmpfile_stdout 01:59:44 INFO - Detecting whether we're running mozinstall >=1.0... 01:59:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 01:59:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 01:59:44 INFO - Reading from file tmpfile_stdout 01:59:44 INFO - Output received: 01:59:44 INFO - Usage: mozinstall [options] installer 01:59:44 INFO - Options: 01:59:44 INFO - -h, --help show this help message and exit 01:59:44 INFO - -d DEST, --destination=DEST 01:59:44 INFO - Directory to install application into. [default: 01:59:44 INFO - "/builds/slave/test-pgo"] 01:59:44 INFO - --app=APP Application being installed. [default: firefox] 01:59:44 INFO - mkdir: /builds/slave/test-pgo/build/application 01:59:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 01:59:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 01:59:59 INFO - Reading from file tmpfile_stdout 01:59:59 INFO - Output received: 01:59:59 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 01:59:59 INFO - Running post-action listener: _resource_record_post_action 01:59:59 INFO - ##### 01:59:59 INFO - ##### Running run-tests step. 01:59:59 INFO - ##### 01:59:59 INFO - Running pre-action listener: _resource_record_pre_action 01:59:59 INFO - Running main action method: run_tests 01:59:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 01:59:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 01:59:59 INFO - Python 2.7.3 01:59:59 INFO - Return code: 0 01:59:59 INFO - grabbing minidump binary from tooltool 01:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:59 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 0x19553b0>, '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 0x19562b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1956740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 01:59:59 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 01:59:59 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 01:59:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:59:59 INFO - Return code: 0 01:59:59 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 01:59:59 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 01:59:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 01:59:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 01:59:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 01:59:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 01:59:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 02:00:00 INFO - Return code: 0 02:00:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 02:00:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost 02:00:00 INFO - Using env: {'DISPLAY': ':0', 02:00:00 INFO - 'HOME': '/home/cltbld', 02:00:00 INFO - 'LANG': 'en_US.UTF-8', 02:00:00 INFO - 'LANGUAGE': 'en_US:en', 02:00:00 INFO - 'LOGNAME': 'cltbld', 02:00:00 INFO - 'MAIL': '/var/mail/cltbld', 02:00:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 02:00:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 02:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:00 INFO - 'MOZ_NO_REMOTE': '1', 02:00:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 02:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:00:00 INFO - 'NO_EM_RESTART': '1', 02:00:00 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 02:00:00 INFO - 'PWD': '/builds/slave/test-pgo', 02:00:00 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 02:00:00 INFO - 'SHELL': '/bin/bash', 02:00:00 INFO - 'SHLVL': '1', 02:00:00 INFO - 'TERM': 'linux', 02:00:00 INFO - 'TMOUT': '86400', 02:00:00 INFO - 'USER': 'cltbld', 02:00:00 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341', 02:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:00 INFO - '_': '/tools/buildbot/bin/python'} 02:00:00 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost'] with output_timeout 3600 02:00:00 INFO - mozversion INFO | application_buildid: 20160305031719 02:00:00 INFO - mozversion INFO | application_changeset: 78fe98c670fcc9a1548ac655ae9a488d940fd9c8 02:00:00 INFO - mozversion INFO | application_display_name: Nightly 02:00:00 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:00:00 INFO - mozversion INFO | application_name: Firefox 02:00:00 INFO - mozversion INFO | application_remotingname: firefox 02:00:00 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:00:00 INFO - mozversion INFO | application_vendor: Mozilla 02:00:00 INFO - mozversion INFO | application_version: 45.0 02:00:00 INFO - mozversion INFO | platform_buildid: 20160305031719 02:00:00 INFO - mozversion INFO | platform_changeset: 78fe98c670fcc9a1548ac655ae9a488d940fd9c8 02:00:00 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:00:00 INFO - mozversion INFO | platform_version: 45.0 02:00:00 INFO - 2016-03-06 02:00:00,564 DEBUG : using testdate: 1457258400 02:00:00 INFO - 2016-03-06 02:00:00,564 DEBUG : actual date: 1457258400 02:00:00 INFO - 2016-03-06 02:00:00,573 INFO : starting webserver on 'localhost:36104' 02:00:00 INFO - 2016-03-06 02:00:00,575 INFO : Starting test suite talos-linux64-ix-013 02:00:00 INFO - 2016-03-06 02:00:00,575 INFO : Starting test dromaeo_css 02:00:00 INFO - 2016-03-06 02:00:00,575 DEBUG : operating with platform_type : linux_ 02:00:00 INFO - 2016-03-06 02:00:00,575 INFO : Initialising browser for dromaeo_css test... 02:00:00 INFO - 2016-03-06 02:00:00,592 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpst1_4u/profile http://localhost:36104/getInfo.html 02:00:04 INFO - 2016-03-06 02:00:04,555 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:00:04 INFO - 2016-03-06 02:00:04,555 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:00:04 INFO - 2016-03-06 02:00:04,555 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:00:04 INFO - 2016-03-06 02:00:04,555 DEBUG : BROWSER_OUTPUT: __metrics 02:00:04 INFO - 2016-03-06 02:00:04,712 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] 02:00:05 INFO - 2016-03-06 02:00:05,885 INFO : Browser initialized. 02:00:05 INFO - 2016-03-06 02:00:05,886 INFO : Running cycle 1/1 for dromaeo_css test... 02:00:05 INFO - 2016-03-06 02:00:05,887 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpst1_4u/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:00:17 INFO - 2016-03-06 02:00:17,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 132648960 02:00:17 INFO - 2016-03-06 02:00:17,572 DEBUG : BROWSER_OUTPUT: 02:01:08 INFO - 2016-03-06 02:01:08,324 DEBUG : BROWSER_OUTPUT: 1457258468320 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] 02:03:16 INFO - 2016-03-06 02:03:16,584 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36104/tests/dromaeo/cssquery-ext.html) 02:03:17 INFO - 2016-03-06 02:03:17,552 DEBUG : BROWSER_OUTPUT: RSS: Main: 221765632 02:03:17 INFO - 2016-03-06 02:03:17,552 DEBUG : BROWSER_OUTPUT: 02:06:19 INFO - 2016-03-06 02:06:19,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/dromaeo/cssquery-ext.html (next: http://localhost:36104/tests/dromaeo/cssquery-jquery.html) 02:06:20 INFO - 2016-03-06 02:06:20,216 DEBUG : BROWSER_OUTPUT: RSS: Main: 205721600 02:06:20 INFO - 2016-03-06 02:06:20,216 DEBUG : BROWSER_OUTPUT: 02:09:21 INFO - 2016-03-06 02:09:21,969 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36104/tests/dromaeo/cssquery-mootools.html) 02:09:22 INFO - 2016-03-06 02:09:22,531 DEBUG : BROWSER_OUTPUT: RSS: Main: 206004224 02:09:22 INFO - 2016-03-06 02:09:22,531 DEBUG : BROWSER_OUTPUT: 02:12:24 INFO - 2016-03-06 02:12:24,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36104/tests/dromaeo/cssquery-prototype.html) 02:12:24 INFO - 2016-03-06 02:12:24,791 DEBUG : BROWSER_OUTPUT: RSS: Main: 198000640 02:12:24 INFO - 2016-03-06 02:12:24,791 DEBUG : BROWSER_OUTPUT: 02:15:27 INFO - 2016-03-06 02:15:27,085 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36104/tests/dromaeo/cssquery-yui.html) 02:15:27 INFO - 2016-03-06 02:15:27,667 DEBUG : BROWSER_OUTPUT: RSS: Main: 197881856 02:15:27 INFO - 2016-03-06 02:15:27,667 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,705 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/dromaeo/cssquery-yui.html (next: http://localhost:36104/tests/dromaeo/cssquery-dojo.html) 02:18:29 INFO - 2016-03-06 02:18:29,952 DEBUG : BROWSER_OUTPUT: RSS: Main: 206897152 02:18:29 INFO - 2016-03-06 02:18:29,952 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,952 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:18:29 INFO - 2016-03-06 02:18:29,953 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:18:29 INFO - 2016-03-06 02:18:29,953 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:18:29 INFO - 2016-03-06 02:18:29,954 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:18:29 INFO - 2016-03-06 02:18:29,954 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3246.6104067511897;3337.1990722226674;3366.9158271043207;3367.3265346930607;3371.2583231689036;5401.162819762933;5435.374931882835;5442.102053161224;5451.264079349292;5482.122860342346;5437.592180586452;5510.421405752399;5545.694986775726;5553.611247212697;5553.8333849984565;4854.951450485485;4928.531789479994;5008.549230569239;5063.164577844651;5080.085584594772;4964.329815474919;4985.152524070917;5038.319826823368;5039.244113382985;5044.571211447032;4901.6078713703;4990.176620857573;4998.450170481244;5049.570786483153;5063.316452278932;4997.42529609096;5092.898142037157;5093.79624815007;5096.159133742928;5123.948760512384;4732.929006064912;5005.124103281911;5007;5036.043151801146;5068.518490743373;5170.819021334235;5216.217567364888;5262.552683021948;5268.130758424893;5269.0252303324005;5830.941690583082;5840.065589505702;5866.413358664142;5872.853178670524;5879.588428809985;5603.859903502404;5734.799282025109;5869.706514674292;5870.236869206976;5880.882382352372;5363.383210930754;5421.376541697716;5424.62027658068;5426.972865135649;5461.863453413656;5942.078977758454;6016.037434010537;6022.728977196037;6023.45788879003;6041.607295525777;5419;5592.664440133605;5659.547236221092;5800.1299805029585;5823.970880145572;5849.268841394825;5856.882862342729;5861.3552509223955;5864.266966629171;5895.55783316253;5783;5826.446487583673;5828.154917536934;5832.766689332379;5836.649801011953;5513.807016754395;5617.157426386075;5728.971355143198;5838.8906107839375;5840.328362238397;4460.0633866887665;4474.664400170001;4484.977575112103;4488.259437192892;4508.639308855284;4037.95013296545;4044.373122166069;4044.737092089005;4077.041895154636;4077.960120169397;5374.5700343972385;5502.119660854244;5561.805336813192;5651.519620832193;5680.943190568124;5935.6735379553675;5991;5994.550408719363;5996.37038110992;5997.13041608973;5338.599605029561;5653.265075540154;5656.971715141398;5658.858528536737;5660.688662123623;5011.348524691767;5093.617978651616;5200.167973124282;5214.426413094557;5221.582273418117;6016.368281330398;6037.0642550404755;6056.031035034462;6056.485198758155;6060.757569697251;5842.532597392198;5921.644701317935;5922.911156332572;5929.110633405024;5932.347441781314;5813.331466881363;5816.389279125631;5891.675957822361;5918.881622367442;5924.11138329254;5713.800016999385;5774.2782152230975;5810.593258471867;5834.329052158886;5834.94165058344;5762.164486149569;5906.793262235801;5921.496672782861;5924.674142922008;5925.822225333247;5577.470140336662;5581.246531718329;5667;5674.659520428788;5703.600747947604;5780.277465316835;5799.5070419014855;5817.738201781015;5848.4151584841175;5855.03391940325;5516.641418307797;5565.693886836263;5744.109663002074;5749.482546570829;5750.281214848144;5703.914441283301;5802.245708057925;5802.4197580241635;5808.419158084158;5846.064629659234;5689.089745640677;5693.259876216065;5713.314402271754;5721.427857214246;5733.0540460823495 02:18:29 INFO - 2016-03-06 02:18:29,955 DEBUG : BROWSER_OUTPUT: |1;ext.html;605.639644411575;642.3608509533016;654.2345455816695;657.3328072006914;658.1805651963307;32659;33463.16342091446;40496.987575310595;41036.794816025955;41039.79480102603;43830.12339753203;44337.77831110848;44347.33478997809;44360.55639443613;44432.3335149973;7972.362211023134;8100.392470564701;9041.321900857445;9129.543522823866;9216.400933939307;10951.123910087173;11200.103991680646;11203.495842687058;11207.383593902332;11208.607698730546;10979.890201097967;11040.723981900437;11090.833637495443;11096.833547496793;11098.223124381297;10762.784744305149;10852.240343175981;10857.782844343068;10858.077063449577;10859.565617375334;10751.83872241917;10891.727706807314;10892.836607450894;10893.782124357469;10900.073493752961;42575.57424425778;43061.13877722428;43102;43126.78436607797;43164.352534712;3520.2783429396914;3579.158897659048;3579.928401431983;3763.7929913854728;3796.52543432071;32034.679653203402;32060.679393206003;32090.679093209004;32323.83838080818;32604.18489537757;3681.3189559931498;3712.684421824148;3716.8141592920247;3803.1633040730994;3825.8660946866726;284928;285922;286490.567547163;287574;288819;63142;64203.35796641974;69379;70807.29192708006;71014.64492677503;63426;63502.68248658819;63661;63673.68163159154;63682;16145.757813632805;16341;16350;16352.509424717278;16361.67276654484;11251.437428128724;11266.380349080879;11274.154438417152;11413.942930285462;11428;21136.674965753755;21550.461238469165;21557;21572.67640985355;21628.35114946554;7914.683412663544;7918.406119540942;7986.560739159286;8089.555074470961;8125.715599954092;1063.4735805776036;1068.5885933915342;1073.8872418395956;1083.3554035348955;1123.3260043973714;29987.250318741768;30238.84880575583;30400.39199216003;30642.233944151576;30709.539356909663;1111.7220694826294;1112.1603189591967;1157.2998336006763;1193.612076075279;1211.8242854786183;12930.418131184118;14001.29993500321;14548.927255363655;14583.854161458461;15133.924330378497;361336;373827;379177;380085;381245;57265.14102288469;57283;57568.13647795287;57994;58061.41938580645;1270.720441502868;1353.1069494133824;1423.6156237815733;1448.5437087317462;1471.7350876842056;26438.603420948315;28716.282092947844;28718.569221462112;29033.274168145967;29781.702182977893;44495.33257001212;44850.775746121064;44857.10285794331;44864.55135448604;47245.527544724115;195189;387940;395349;397139;397684;33997.3200535983;34337.701527286365;34682.82658586691;34685.959421217405;34701;46898.296525551465;47047;47215.527844722485;47275.05449890914;48014;38854.71580262336;39895.40156897707;40078.79960600178;40747.796261018506;40945.59054409418;36968.445473317384;38225.808870955465;38872;41195;42080.15839683251;34256;34479;34962.300753985284;35075.29849403049;35151.29697406089;30636.234094146934;32336.67663323337;33135.33729325352;33186.33627327488;33545.16137096592;25509.23472295834;26182;26450.603240951787;27051.72948270571;27223.86388068047 02:18:29 INFO - 2016-03-06 02:18:29,959 DEBUG : BROWSER_OUTPUT: |2;jquery.html;375.93984962406006;378.48148037189054;383.82152299180893;383.9980800096;384.4041735310269;14751.409943602242;16779;17020.234089465972;18501.72247416288;18511.5372115697;16905.7464138038;16975.83024169761;17147;17202.56993575158;17266.913665431686;5367.570594352453;5568.888622227553;5587;5587.106063029916;5590.5527557795485;4225.915481690365;4346.565343465644;4349.608535231829;5580.107182850745;5940.287165540141;5972.850678733023;6024.879502409949;6033.969830150865;6048.062550304688;6051.485623721989;5851.327097383792;5995.850103747398;6000.489958353545;6007.309159446676;6012.5791194616395;5231.738413079331;5334;5334.786608535654;5335.253064570963;5354.892902141974;20362.389128326173;21220.25729099474;21220.575588488296;21445.142194312208;21767;2345.7888790008983;2372.074890792592;2400.5078958813574;2401.1595941420537;2467.568175569268;13642.249676267802;13898.722025559433;13934.790978135235;13951.930240348733;14300;1917.5206198450387;1941.0973897137899;1959.9020048997493;1993.9700904486444;2009.3770931011434;61206.38793612007;61835.690821545606;63198;63344;63475;10013.052303508235;10629.255952083435;10636.095931845879;10639;10700.197485188643;13923.860761392256;14060.929695351662;14070.085444446078;14096.506622268376;14102.647433814032;7979.72070977513;7988.041435027834;8004.559749213733;8032.795080737819;8059.113497515269;7439.256074392626;7442.441816863757;7475;7482.6632801524065;7516.699332026657;6257.7184026718905;6311.936880631227;6319.968400158062;6336.366363363627;6376.489880809524;1742.555648309673;1815.6277963017549;1823.8358547730768;1912.3975947576469;1921.1450904347432;443.67168295461124;446.33718927392795;457.732226647415;961.8557216417594;1094.6715985204407;10984.725381865517;16896.746548801777;16902.83097169037;17032.914835425745;18785.24859005652;920.2867777472512;975.7511834482161;994.264244459095;1012.8885822559513;1026.3636545341787;9888.258380621339;10199.796004080024;10283.07452329294;10324.174066074696;10344.120749736207;687218;730500;745142;762420;781661;79688;82116;82826;83789.58105209557;85252.14747852566;1153.7461758413135;1178.263585259213;1189.4469071881617;1198.1553005131361;1212.9999999999823;16074.43739469113;16113.516594502184;16115.597110072342;16213.918930405744;16299.348026078824;36434.27131457303;36746.63253367432;37053;37467.62532374641;38144.61855381522;49100;49878;50630.49369506258;51079;51636.74181629218;9580.616775328908;9874.358166719141;10122.291439599157;10129.797404052026;10138.949305253427;11633.360165190657;11722.824157637813;17630.1184940755;17774.555636109202;17859.196336164645;14605.342759575633;14864;14867.628309292355;15065.849341506444;15248.3900643973;14429.20639364803;14530.709385812435;14554.85445145535;15174.165420901956;15342.30959606798;15572.221388930735;15756.557733551317;15989.760153597472;16002.119883406524;16090.356385744439;9582.952085239529;9909.306348555532;10010.949945250228;10166.237532185263;10177.796444071226;10810.945945270223;14240.857591424368;14328.928355358574;14845.109293441996;15174.468893588672 02:18:29 INFO - 2016-03-06 02:18:29,959 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1511.5389806109133;1520.0195873661496;1702.7701260329868;1824.0697244405353;1856.0905156473327;7642.923570764291;7987.7204297849585;7990.640421181046;7996.200379962008;8012.639431225594;6901.378875901168;7043.26045765195;7404.888926666104;7704.807379815507;7796.493227940194;2275.465265662572;2285.02886273334;2311.503026849227;2311.52613714189;2311.7688231176917;1993.372087792348;2326.174208156097;2329.848559843613;2403.0868270057354;2403.9879800600975;2026.493376655836;2287.1537531113513;2325.6046472099697;2328.429534763985;2330.941726456835;2275.0217406515276;2311.6879221305076;2315.9189428369928;2321.454458202321;2322.988385058064;2292.633178691418;2302.5855346037706;2327.034280773477;2330.953380932389;2371.395294199985;7872.409569282269;7932.762017139495;7954;7954.125046244909;7957.442978991473;4706.835260765891;4796.184648609745;4806.3511425957395;4806.831760888352;4806.95193048072;2941.970580294191;2987.790854640176;2991.5811786349927;2997.925051873699;3094.8452577371268;1009.3893194617224;1028.0850043461326;1036.0778906772978;1043.4608951796115;1072.2119242356862;10843;10902.291351062235;10905;10915.290506117077;10918.1811364148;4209.095044565433;4225.049363893099;4280;4283.721558098714;4287.935680964788;9363.859542106873;9367;9375.34372593912;9376.390534615228;9383.202427793578;4820.831270905502;4824.975875120602;4825.638077144228;4829.034193161382;4831.9275210871865;4347.369631403492;4386.144701783185;4387.692861499664;4387.934180987288;4389.275769497997;3761.2101458693983;3774.641409066134;3805.2009078093356;3829.5213098362706;3839.0594304395045;1057.5769692123058;1159.5014143918027;1194.8864857838491;1226.693326668333;1232.4577186038114;1124.8144056230794;1127.464454384161;1182.2492717124517;1386.4939297156466;1458.5332693537966;7468.253174682597;7492.962535187398;7539.170691223961;7592.696292148252;7602.429817763579;1174.448009435564;1188.6374655730085;1200.087933170779;1212.9514819407125;1213.5327898759042;5971.731272092767;6056.66688332146;6080.027195648675;6083.026715725463;6092.72582733778;9078.137576930181;9194.264458843276;9331.486768227813;9341;9354.064593540592;9133.680321188727;9245;9263.166315031545;9286.90713092874;9288.72133835986;7268.418526517866;7420.109586849505;7445.851082978418;7506.624668766649;7511.248875112555;7479.065116860393;7527.360174385239;7529.360004399688;7574.545527268381;7580.469367144247;8373.037100733494;8495.830083398174;8541.444806087584;8555.058943516333;8556.016058153393;8477.915220847712;8659;8705.564721764013;8714.30285577165;8728.127187281476;6884.14667659289;6916;6947;6997.230304666585;6998.405135563534;7564.546127232384;7605.35354494852;7693.615319233904;7699.230076992256;7721.884171737541;6258.123862659322;7151.249118842655;7154.678039488132;7268.781936541912;7344.742933997284;6312.817673495251;6544.869102618016;7143.428525718034;7168.820779480555;7216;7266;7305.780826575211;7314;7324.4140468763435;7336.633168341668;6758.628275444898;6787.219469760843;6806.285340039224;6809;6830.829229269308;6848.486363522656;6899.6205208712;6920.550164239308;6924.757633482804;6940.479464040116 02:18:29 INFO - 2016-03-06 02:18:29,959 DEBUG : BROWSER_OUTPUT: |4;prototype.html;360.29382410475466;365.9835307411166;366.83492428407214;372.1886287892394;374.6085340818842;6047.969760151204;6111.602745821524;6124.295705993812;6168.53735969803;6194.938050619492;5746.396628354015;5852.385499522553;5902.822915312537;5914.6155499892475;6037.094435834627;4710.3641008463765;4716.151092803286;4727.030958653468;4731.266653668687;4740.6918550294295;4716.481187069419;4719.457262414815;4721.669483136182;4723.811047558093;4730.716157030589;4688;4690.413698287714;4693.20215563355;4695.1079294934025;4710.811567537295;4624.8150073997;4694.530546945278;4697.694649847749;4701.200795864711;4714.622830173578;4159.729617574848;4162.5629308922735;4163.10493243951;4163.5836416358425;4182.498100227992;5747.252857128588;5756.309242890838;5761.53907687384;5768.769249230026;5770.509506691937;5491.203775452577;5499.532539734128;5500.037493438632;5502.972485137589;5507.311586051744;6203.472704820096;6421.903671444932;6422.550421470452;6432.549721519449;6434.7104380303;4498.055408364247;4527.026689261792;4529.116822219668;4531.932021019687;4537.591616754508;6677.5325727199115;6747.426468750162;6749.156355455568;6749.628770417625;6750.8312292192595;4512.909741805145;4627.490975992638;4651.069786042805;4652.604528615038;4660.627149828006;4697.95302046982;4699.859004229878;4700.95299047012;4705.835295764632;4709.411323584552;5419.810306639249;5423.593230507728;5426.158945363475;5430.049741295257;5434.83695489136;6505.544611877218;6509.641969691617;6512.674366281667;6514.5114116441455;6517.152770139852;1682.3522943666533;1710.9828901710823;1714.5199344183648;1715.682598719241;1721.440531827136;1127.1715289262386;1164.173436859839;1183.402381797187;1187.1808452167977;1193.3317342288346;6194.163787888668;6221.035739460391;6237.812865614039;6241.500679945593;6244.718987645567;6589.9341006590275;6592.406683398517;6599.274079851212;6600.438962688226;6612.7354905804195;6124.663143527148;6232.439080482778;6240.500759939194;6241.407066328691;6252.656103914237;3561.1809283864336;3600.225951420457;3602.1354874829854;3605.0446631642635;3606.098475381155;6753.45972322213;6770.72917083321;6780.32196780318;6781.084553585301;6788.49086318528;6651.035599837996;6722.058911752359;6755.628440435725;6766.018927255521;6770.69531871067;6695.899561506581;6743.966280168666;6752.831179220559;6756.324367563205;6759.121314229217;6572.769953051811;6576.901346479711;6577.210734711865;6580.8354791130605;6583.63789991555;6370.522210834299;6480.157579514633;6480.54636175482;6490.415862572391;6490.870182596227;6733.494987875831;6733.595984240961;6747.730090796314;6748.493862960396;6752.493562982698;4761.595264402426;4767.094252092092;4768.284757286435;4770.141374552475;4771.594414474808;6419.486441084796;6555.934440655532;6581.2102547694585;6581.2102547694585;6581.407673309425;6382.87234255303;6390.041493775971;6391.360863913572;6392.552521323462;6410.61536307823;6361;6369.172007638977;6383.96808015957;6386;6404.295527492065;6285.497160227263;6303.653299068412;6305.90541141874;6308.873822523432;6366.9044964326495;6611.30581288977;6613.735450581923;6621.702023408862;6623;6623.039659249478;6445.838854028687;6451;6453.128827608401;6464.191976003;6472.093906853139 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: |5;yui.html;914.1361413464277;923.2429407885537;928.809594033223;930.581238442701;940.5720397219266;13759.174449533022;14012.15927044378;14125.293735313231;15306.923465382657;15432;14208.360623771929;14277.286135693199;14298.142111473318;14299.213543255142;14304.928475357634;722.9819254518653;763.8052296664371;830.4270053662983;893.4014210478962;912.6075787411411;899.235649697755;906.9410488318238;913.0002647101413;916.5005072235648;916.7983043730402;948.6869333120054;955.5317894231846;962.1004360922525;962.1869520255408;962.6245764151986;754.9849003019937;792.5046845721424;878.5124256037897;934.486032682029;939.229831538139;693.6774399904069;696.2028477393407;696.3210869402343;696.5437638346891;697.2504557600599;13668.384922678504;13682.384292706853;13713.17720936747;13755.449782008707;13816.654583635389;11824.11319151067;12562.120651554394;12903.579673795492;13180.34098295091;13269.203847769068;10918.945405272923;10930.289531180448;11064.114870810316;11107.222494425394;11601.709957251052;3768.7550309229814;3789.412641040643;3800.2209547043067;3810.4474851146706;3875.6318149775725;44439;45469.317960230626;45488;45834.54165458336;49347.50652493501;10188.184945204366;10539.789204215873;10809;10818.67543973682;10967.177461690406;17172.313107475562;17191.398301059402;17214.397496087826;17222.569435763955;17247.310107595807;7844.058712954368;7845.37237021037;7925.484843485155;8129.75610731679;8165.38759593021;4335.393044973706;4355;4379.627731642782;4402.537733537996;4561.680682352204;3124.3907438049364;3132.8433578321446;3153.495440729472;3153.984230078881;3155.5266709993684;588.2147333309994;591.1280860730388;605.6214865708932;606.8149214489623;612.6446660936646;649.6524359467662;687.3058211206617;701.8841891087914;748.2480107491936;751.6279441676405;8722.47665140081;9047.77380565478;9475.147236748726;9611.327207095435;9951.25365597583;706.8480276740526;766.5975362934392;776.1811289090065;788.4993029426241;788.7712563356562;4303.892402689895;4376.256036473807;4443.466783985878;4452.710573812692;4493.640508759357;57673;57695.13457298144;60052.09921851176;62969.685151574864;63234;51976.22035669468;52467;52762;53924.73037634787;54282.45717542774;49054;49204;49984;50286.49713502891;50432.24351634655;9237.861432078658;9408.717738467856;9472.336936414385;9573.808523829624;9690.467024313448;35369.646303537666;36238;36756.632433676394;36759.81620091882;36924.44613330856;42489;45825.54174458346;47758;48400;48555.27167092567;11362.54549817998;11584.594539191088;11648;11715.121365897421;11931.4034298283;12107.939460302641;12407.93796031014;12490.250584964568;12528.248305101723;12850.866062587495;11435.714107147387;11458.656240312805;11692.298462092302;11702;11876.881231187923;11988.160828741904;12204.633860984184;12279;12335.444904979477;12519.311437871005;10943;10975.924418951116;11019.393933333744;11152.55389784432;11220.326780393203;9461.952690236505;9470.715878523655;10161.288709790244;10221.59113635476;10340.12108970746;10204;10321.071103600712;10362.222833287684;10385;10407.167426606005 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,715 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: __startTimestamp1457259509952__endTimestamp 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,960 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: Number of tests: 6 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5444.86 Median:5659.55 stddev:589.38 (10.4%) stddev-sans-first:565.39 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: Values: 3246.6 3337.2 3366.9 3367.3 3371.3 5401.2 5435.4 5442.1 5451.3 5482.1 5437.6 5510.4 5545.7 5553.6 5553.8 4855.0 4928.5 5008.5 5063.2 5080.1 4964.3 4985.2 5038.3 5039.2 5044.6 4901.6 4990.2 4998.5 5049.6 5063.3 4997.4 5092.9 5093.8 5096.2 5123.9 4732.9 5005.1 5007.0 5036.0 5068.5 5170.8 5216.2 5262.6 5268.1 5269.0 5830.9 5840.1 5866.4 5872.9 5879.6 5603.9 5734.8 5869.7 5870.2 5880.9 5363.4 5421.4 5424.6 5427.0 5461.9 5942.1 6016.0 6022.7 6023.5 6041.6 5419.0 5592.7 5659.5 5800.1 5824.0 5849.3 5856.9 5861.4 5864.3 5895.6 5783.0 5826.4 5828.2 5832.8 5836.6 5513.8 5617.2 5729.0 5838.9 5840.3 4460.1 4474.7 4485.0 4488.3 4508.6 4038.0 4044.4 4044.7 4077.0 4078.0 5374.6 5502.1 5561.8 5651.5 5680.9 5935.7 5991.0 5994.6 5996.4 5997.1 5338.6 5653.3 5657.0 5658.9 5660.7 5011.3 5093.6 5200.2 5214.4 5221.6 6016.4 6037.1 6056.0 6056.5 6060.8 5842.5 5921.6 5922.9 5929.1 5932.3 5813.3 5816.4 5891.7 5918.9 5924.1 5713.8 5774.3 5810.6 5834.3 5834.9 5762.2 5906.8 5921.5 5924.7 5925.8 5577.5 5581.2 5667.0 5674.7 5703.6 5780.3 5799.5 5817.7 5848.4 5855.0 5516.6 5565.7 5744.1 5749.5 5750.3 5703.9 5802.2 5802.4 5808.4 5846.1 5689.1 5693.3 5713.3 5721.4 5733.1 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51603.78 Median:34408.35 stddev:90407.61 (262.7%) stddev-sans-first:90580.14 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: Values: 605.6 642.4 654.2 657.3 658.2 32659.0 33463.2 40497.0 41036.8 41039.8 43830.1 44337.8 44347.3 44360.6 44432.3 7972.4 8100.4 9041.3 9129.5 9216.4 10951.1 11200.1 11203.5 11207.4 11208.6 10979.9 11040.7 11090.8 11096.8 11098.2 10762.8 10852.2 10857.8 10858.1 10859.6 10751.8 10891.7 10892.8 10893.8 10900.1 42575.6 43061.1 43102.0 43126.8 43164.4 3520.3 3579.2 3579.9 3763.8 3796.5 32034.7 32060.7 32090.7 32323.8 32604.2 3681.3 3712.7 3716.8 3803.2 3825.9 284928.0 285922.0 286490.6 287574.0 288819.0 63142.0 64203.4 69379.0 70807.3 71014.6 63426.0 63502.7 63661.0 63673.7 63682.0 16145.8 16341.0 16350.0 16352.5 16361.7 11251.4 11266.4 11274.2 11413.9 11428.0 21136.7 21550.5 21557.0 21572.7 21628.4 7914.7 7918.4 7986.6 8089.6 8125.7 1063.5 1068.6 1073.9 1083.4 1123.3 29987.3 30238.8 30400.4 30642.2 30709.5 1111.7 1112.2 1157.3 1193.6 1211.8 12930.4 14001.3 14548.9 14583.9 15133.9 361336.0 373827.0 379177.0 380085.0 381245.0 57265.1 57283.0 57568.1 57994.0 58061.4 1270.7 1353.1 1423.6 1448.5 1471.7 26438.6 28716.3 28718.6 29033.3 29781.7 44495.3 44850.8 44857.1 44864.6 47245.5 195189.0 387940.0 395349.0 397139.0 397684.0 33997.3 34337.7 34682.8 34686.0 34701.0 46898.3 47047.0 47215.5 47275.1 48014.0 38854.7 39895.4 40078.8 40747.8 40945.6 36968.4 38225.8 38872.0 41195.0 42080.2 34256.0 34479.0 34962.3 35075.3 35151.3 30636.2 32336.7 33135.3 33186.3 33545.2 25509.2 26182.0 26450.6 27051.7 27223.9 02:18:29 INFO - 2016-03-06 02:18:29,961 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,962 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:35126.32 Median:21332.86 stddev:121075.10 (567.6%) stddev-sans-first:121386.63 02:18:29 INFO - 2016-03-06 02:18:29,963 DEBUG : BROWSER_OUTPUT: Values: 375.9 378.5 383.8 384.0 384.4 14751.4 16779.0 17020.2 18501.7 18511.5 16905.7 16975.8 17147.0 17202.6 17266.9 5367.6 5568.9 5587.0 5587.1 5590.6 4225.9 4346.6 4349.6 5580.1 5940.3 5972.9 6024.9 6034.0 6048.1 6051.5 5851.3 5995.9 6000.5 6007.3 6012.6 5231.7 5334.0 5334.8 5335.3 5354.9 20362.4 21220.3 21220.6 21445.1 21767.0 2345.8 2372.1 2400.5 2401.2 2467.6 13642.2 13898.7 13934.8 13951.9 14300.0 1917.5 1941.1 1959.9 1994.0 2009.4 61206.4 61835.7 63198.0 63344.0 63475.0 10013.1 10629.3 10636.1 10639.0 10700.2 13923.9 14060.9 14070.1 14096.5 14102.6 7979.7 7988.0 8004.6 8032.8 8059.1 7439.3 7442.4 7475.0 7482.7 7516.7 6257.7 6311.9 6320.0 6336.4 6376.5 1742.6 1815.6 1823.8 1912.4 1921.1 443.7 446.3 457.7 961.9 1094.7 10984.7 16896.7 16902.8 17032.9 18785.2 920.3 975.8 994.3 1012.9 1026.4 9888.3 10199.8 10283.1 10324.2 10344.1 687218.0 730500.0 745142.0 762420.0 781661.0 79688.0 82116.0 82826.0 83789.6 85252.1 1153.7 1178.3 1189.4 1198.2 1213.0 16074.4 16113.5 16115.6 16213.9 16299.3 36434.3 36746.6 37053.0 37467.6 38144.6 49100.0 49878.0 50630.5 51079.0 51636.7 9580.6 9874.4 10122.3 10129.8 10138.9 11633.4 11722.8 17630.1 17774.6 17859.2 14605.3 14864.0 14867.6 15065.8 15248.4 14429.2 14530.7 14554.9 15174.2 15342.3 15572.2 15756.6 15989.8 16002.1 16090.4 9583.0 9909.3 10010.9 10166.2 10177.8 10810.9 14240.9 14328.9 14845.1 15174.5 02:18:29 INFO - 2016-03-06 02:18:29,963 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,963 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5559.67 Median:6285.47 stddev:2852.15 (45.4%) stddev-sans-first:2843.92 02:18:29 INFO - 2016-03-06 02:18:29,964 DEBUG : BROWSER_OUTPUT: Values: 1511.5 1520.0 1702.8 1824.1 1856.1 7642.9 7987.7 7990.6 7996.2 8012.6 6901.4 7043.3 7404.9 7704.8 7796.5 2275.5 2285.0 2311.5 2311.5 2311.8 1993.4 2326.2 2329.8 2403.1 2404.0 2026.5 2287.2 2325.6 2328.4 2330.9 2275.0 2311.7 2315.9 2321.5 2323.0 2292.6 2302.6 2327.0 2331.0 2371.4 7872.4 7932.8 7954.0 7954.1 7957.4 4706.8 4796.2 4806.4 4806.8 4807.0 2942.0 2987.8 2991.6 2997.9 3094.8 1009.4 1028.1 1036.1 1043.5 1072.2 10843.0 10902.3 10905.0 10915.3 10918.2 4209.1 4225.0 4280.0 4283.7 4287.9 9363.9 9367.0 9375.3 9376.4 9383.2 4820.8 4825.0 4825.6 4829.0 4831.9 4347.4 4386.1 4387.7 4387.9 4389.3 3761.2 3774.6 3805.2 3829.5 3839.1 1057.6 1159.5 1194.9 1226.7 1232.5 1124.8 1127.5 1182.2 1386.5 1458.5 7468.3 7493.0 7539.2 7592.7 7602.4 1174.4 1188.6 1200.1 1213.0 1213.5 5971.7 6056.7 6080.0 6083.0 6092.7 9078.1 9194.3 9331.5 9341.0 9354.1 9133.7 9245.0 9263.2 9286.9 9288.7 7268.4 7420.1 7445.9 7506.6 7511.2 7479.1 7527.4 7529.4 7574.5 7580.5 8373.0 8495.8 8541.4 8555.1 8556.0 8477.9 8659.0 8705.6 8714.3 8728.1 6884.1 6916.0 6947.0 6997.2 6998.4 7564.5 7605.4 7693.6 7699.2 7721.9 6258.1 7151.2 7154.7 7268.8 7344.7 6312.8 6544.9 7143.4 7168.8 7216.0 7266.0 7305.8 7314.0 7324.4 7336.6 6758.6 6787.2 6806.3 6809.0 6830.8 6848.5 6899.6 6920.6 6924.8 6940.5 02:18:29 INFO - 2016-03-06 02:18:29,964 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,964 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5405.00 Median:6194.55 stddev:1585.56 (25.6%) stddev-sans-first:1544.13 02:18:29 INFO - 2016-03-06 02:18:29,964 DEBUG : BROWSER_OUTPUT: Values: 360.3 366.0 366.8 372.2 374.6 6048.0 6111.6 6124.3 6168.5 6194.9 5746.4 5852.4 5902.8 5914.6 6037.1 4710.4 4716.2 4727.0 4731.3 4740.7 4716.5 4719.5 4721.7 4723.8 4730.7 4688.0 4690.4 4693.2 4695.1 4710.8 4624.8 4694.5 4697.7 4701.2 4714.6 4159.7 4162.6 4163.1 4163.6 4182.5 5747.3 5756.3 5761.5 5768.8 5770.5 5491.2 5499.5 5500.0 5503.0 5507.3 6203.5 6421.9 6422.6 6432.5 6434.7 4498.1 4527.0 4529.1 4531.9 4537.6 6677.5 6747.4 6749.2 6749.6 6750.8 4512.9 4627.5 4651.1 4652.6 4660.6 4698.0 4699.9 4701.0 4705.8 4709.4 5419.8 5423.6 5426.2 5430.0 5434.8 6505.5 6509.6 6512.7 6514.5 6517.2 1682.4 1711.0 1714.5 1715.7 1721.4 1127.2 1164.2 1183.4 1187.2 1193.3 6194.2 6221.0 6237.8 6241.5 6244.7 6589.9 6592.4 6599.3 6600.4 6612.7 6124.7 6232.4 6240.5 6241.4 6252.7 3561.2 3600.2 3602.1 3605.0 3606.1 6753.5 6770.7 6780.3 6781.1 6788.5 6651.0 6722.1 6755.6 6766.0 6770.7 6695.9 6744.0 6752.8 6756.3 6759.1 6572.8 6576.9 6577.2 6580.8 6583.6 6370.5 6480.2 6480.5 6490.4 6490.9 6733.5 6733.6 6747.7 6748.5 6752.5 4761.6 4767.1 4768.3 4770.1 4771.6 6419.5 6555.9 6581.2 6581.2 6581.4 6382.9 6390.0 6391.4 6392.6 6410.6 6361.0 6369.2 6384.0 6386.0 6404.3 6285.5 6303.7 6305.9 6308.9 6366.9 6611.3 6613.7 6621.7 6623.0 6623.0 6445.8 6451.0 6453.1 6464.2 6472.1 02:18:29 INFO - 2016-03-06 02:18:29,964 DEBUG : BROWSER_OUTPUT: 02:18:29 INFO - 2016-03-06 02:18:29,965 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14327.96 Median:24441.23 stddev:16461.98 (67.4%) stddev-sans-first:16477.34 02:18:29 INFO - 2016-03-06 02:18:29,966 DEBUG : BROWSER_OUTPUT: Values: 914.1 923.2 928.8 930.6 940.6 13759.2 14012.2 14125.3 15306.9 15432.0 14208.4 14277.3 14298.1 14299.2 14304.9 723.0 763.8 830.4 893.4 912.6 899.2 906.9 913.0 916.5 916.8 948.7 955.5 962.1 962.2 962.6 755.0 792.5 878.5 934.5 939.2 693.7 696.2 696.3 696.5 697.3 13668.4 13682.4 13713.2 13755.4 13816.7 11824.1 12562.1 12903.6 13180.3 13269.2 10918.9 10930.3 11064.1 11107.2 11601.7 3768.8 3789.4 3800.2 3810.4 3875.6 44439.0 45469.3 45488.0 45834.5 49347.5 10188.2 10539.8 10809.0 10818.7 10967.2 17172.3 17191.4 17214.4 17222.6 17247.3 7844.1 7845.4 7925.5 8129.8 8165.4 4335.4 4355.0 4379.6 4402.5 4561.7 3124.4 3132.8 3153.5 3154.0 3155.5 588.2 591.1 605.6 606.8 612.6 649.7 687.3 701.9 748.2 751.6 8722.5 9047.8 9475.1 9611.3 9951.3 706.8 766.6 776.2 788.5 788.8 4303.9 4376.3 4443.5 4452.7 4493.6 57673.0 57695.1 60052.1 62969.7 63234.0 51976.2 52467.0 52762.0 53924.7 54282.5 49054.0 49204.0 49984.0 50286.5 50432.2 9237.9 9408.7 9472.3 9573.8 9690.5 35369.6 36238.0 36756.6 36759.8 36924.4 42489.0 45825.5 47758.0 48400.0 48555.3 11362.5 11584.6 11648.0 11715.1 11931.4 12107.9 12407.9 12490.3 12528.2 12850.9 11435.7 11458.7 11692.3 11702.0 11876.9 11988.2 12204.6 12279.0 12335.4 12519.3 10943.0 10975.9 11019.4 11152.6 11220.3 9462.0 9470.7 10161.3 10221.6 10340.1 10204.0 10321.1 10362.2 10385.0 10407.2 02:18:29 INFO - 2016-03-06 02:18:29,966 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:18:29 INFO - 2016-03-06 02:18:29,966 DEBUG : BROWSER_OUTPUT: 02:18:30 INFO - 2016-03-06 02:18:30,502 INFO : Browser exited with error code: 0 02:18:30 INFO - 2016-03-06 02:18:30,525 INFO : Completed test dromaeo_css (00:18:29) 02:18:30 INFO - 2016-03-06 02:18:30,525 INFO : Starting test kraken 02:18:30 INFO - 2016-03-06 02:18:30,525 DEBUG : operating with platform_type : linux_ 02:18:30 INFO - 2016-03-06 02:18:30,525 INFO : Initialising browser for kraken test... 02:18:30 INFO - 2016-03-06 02:18:30,532 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuDLHKg/profile http://localhost:36104/getInfo.html 02:18:34 INFO - 2016-03-06 02:18:34,734 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:18:34 INFO - 2016-03-06 02:18:34,734 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:18:34 INFO - 2016-03-06 02:18:34,734 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:18:34 INFO - 2016-03-06 02:18:34,734 DEBUG : BROWSER_OUTPUT: __metrics 02:18:34 INFO - 2016-03-06 02:18:34,891 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] 02:18:35 INFO - 2016-03-06 02:18:35,757 INFO : Browser initialized. 02:18:35 INFO - 2016-03-06 02:18:35,758 INFO : Running cycle 1/1 for kraken test... 02:18:35 INFO - 2016-03-06 02:18:35,758 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuDLHKg/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:18:47 INFO - 2016-03-06 02:18:47,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 134451200 02:18:47 INFO - 2016-03-06 02:18:47,395 DEBUG : BROWSER_OUTPUT: 02:18:49 INFO - 2016-03-06 02:18:49,850 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36104/tests/kraken/driver.html?testName=audio-beat-detection) 02:18:50 INFO - 2016-03-06 02:18:50,416 DEBUG : BROWSER_OUTPUT: RSS: Main: 165769216 02:18:50 INFO - 2016-03-06 02:18:50,416 DEBUG : BROWSER_OUTPUT: 02:18:52 INFO - 2016-03-06 02:18:52,980 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36104/tests/kraken/driver.html?testName=audio-dft) 02:18:53 INFO - 2016-03-06 02:18:53,542 DEBUG : BROWSER_OUTPUT: RSS: Main: 164188160 02:18:53 INFO - 2016-03-06 02:18:53,542 DEBUG : BROWSER_OUTPUT: 02:18:56 INFO - 2016-03-06 02:18:56,341 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36104/tests/kraken/driver.html?testName=audio-fft) 02:18:56 INFO - 2016-03-06 02:18:56,902 DEBUG : BROWSER_OUTPUT: RSS: Main: 162394112 02:18:56 INFO - 2016-03-06 02:18:56,903 DEBUG : BROWSER_OUTPUT: 02:18:58 INFO - 2016-03-06 02:18:58,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36104/tests/kraken/driver.html?testName=audio-oscillator) 02:18:59 INFO - 2016-03-06 02:18:59,432 DEBUG : BROWSER_OUTPUT: RSS: Main: 160813056 02:18:59 INFO - 2016-03-06 02:18:59,433 DEBUG : BROWSER_OUTPUT: 02:19:01 INFO - 2016-03-06 02:19:01,613 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36104/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:19:02 INFO - 2016-03-06 02:19:02,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 160354304 02:19:02 INFO - 2016-03-06 02:19:02,176 DEBUG : BROWSER_OUTPUT: 02:19:05 INFO - 2016-03-06 02:19:05,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36104/tests/kraken/driver.html?testName=imaging-darkroom) 02:19:05 INFO - 2016-03-06 02:19:05,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 158707712 02:19:05 INFO - 2016-03-06 02:19:05,582 DEBUG : BROWSER_OUTPUT: 02:19:08 INFO - 2016-03-06 02:19:08,896 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36104/tests/kraken/driver.html?testName=imaging-desaturate) 02:19:09 INFO - 2016-03-06 02:19:09,463 DEBUG : BROWSER_OUTPUT: RSS: Main: 159711232 02:19:09 INFO - 2016-03-06 02:19:09,463 DEBUG : BROWSER_OUTPUT: 02:19:12 INFO - 2016-03-06 02:19:12,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36104/tests/kraken/driver.html?testName=json-parse-financial) 02:19:12 INFO - 2016-03-06 02:19:12,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 160948224 02:19:12 INFO - 2016-03-06 02:19:12,708 DEBUG : BROWSER_OUTPUT: 02:19:14 INFO - 2016-03-06 02:19:14,262 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36104/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:19:14 INFO - 2016-03-06 02:19:14,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 166617088 02:19:14 INFO - 2016-03-06 02:19:14,825 DEBUG : BROWSER_OUTPUT: 02:19:16 INFO - 2016-03-06 02:19:16,595 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:19:17 INFO - 2016-03-06 02:19:17,185 DEBUG : BROWSER_OUTPUT: RSS: Main: 164618240 02:19:17 INFO - 2016-03-06 02:19:17,186 DEBUG : BROWSER_OUTPUT: 02:19:19 INFO - 2016-03-06 02:19:19,198 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:19:19 INFO - 2016-03-06 02:19:19,771 DEBUG : BROWSER_OUTPUT: RSS: Main: 169955328 02:19:19 INFO - 2016-03-06 02:19:19,771 DEBUG : BROWSER_OUTPUT: 02:19:22 INFO - 2016-03-06 02:19:22,127 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:19:22 INFO - 2016-03-06 02:19:22,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 178962432 02:19:22 INFO - 2016-03-06 02:19:22,714 DEBUG : BROWSER_OUTPUT: 02:19:25 INFO - 2016-03-06 02:19:25,261 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:19:25 INFO - 2016-03-06 02:19:25,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 175644672 02:19:25 INFO - 2016-03-06 02:19:25,831 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36104/tests/kraken/driver.html?testName=ai-astar) 02:19:27 INFO - 2016-03-06 02:19:27,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 233914368 02:19:27 INFO - 2016-03-06 02:19:27,765 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,769 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:19:27 INFO - 2016-03-06 02:19:27,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:19:27 INFO - 2016-03-06 02:19:27,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:19:27 INFO - 2016-03-06 02:19:27,769 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:19:27 INFO - 2016-03-06 02:19:27,769 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;104;109;111;109;100;109;109;109 02:19:27 INFO - 2016-03-06 02:19:27,769 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;120;151;156;124;145;154;119;170;123 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;160;158;159;161;160;161;159;159 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;71;148;71;71;73;72;151;71 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;133;90;93;92;139;91;92;145 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;110;109;109;109;109;109 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;145;146;145;147;145;145 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;95;95;96;96;95;95;95 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;60;60;61;58;59;65;59;57;57 02:19:27 INFO - 2016-03-06 02:19:27,770 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;64;65;63;64;64;64;65;64 02:19:27 INFO - 2016-03-06 02:19:27,771 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;85;78;78;79;85;78;78;94;78 02:19:27 INFO - 2016-03-06 02:19:27,771 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;118;117;112;111;116;124;122;116;113 02:19:27 INFO - 2016-03-06 02:19:27,771 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;148;149;146;139;145;156;145;148;155;135 02:19:27 INFO - 2016-03-06 02:19:27,771 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;67;66;69;65;64;64;65;65;65 02:19:27 INFO - 2016-03-06 02:19:27,771 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:19:27 INFO - 2016-03-06 02:19:27,771 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:19:27 INFO - 2016-03-06 02:19:27,771 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,883 02:19:27 INFO - 2016-03-06 02:19:27,772 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:19:27 INFO - 2016-03-06 02:19:27,772 DEBUG : BROWSER_OUTPUT: __startTimestamp1457259567767__endTimestamp 02:19:27 INFO - 2016-03-06 02:19:27,772 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,775 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:19:27 INFO - 2016-03-06 02:19:27,775 DEBUG : BROWSER_OUTPUT: Number of tests: 14 02:19:27 INFO - 2016-03-06 02:19:27,775 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,775 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.10 Median:109.00 stddev:3.45 (3.2%) stddev-sans-first:3.59 02:19:27 INFO - 2016-03-06 02:19:27,775 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 104.0 109.0 111.0 109.0 100.0 109.0 109.0 109.0 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.60 Median:152.50 stddev:18.42 (12.1%) stddev-sans-first:18.99 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: Values: 154.0 120.0 151.0 156.0 124.0 145.0 154.0 119.0 170.0 123.0 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 160.0 158.0 159.0 161.0 160.0 161.0 159.0 159.0 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.30 Median:71.50 stddev:32.80 (45.9%) stddev-sans-first:34.32 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 71.0 148.0 71.0 71.0 73.0 72.0 151.0 71.0 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.00 stddev:22.84 (24.8%) stddev-sans-first:23.79 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 133.0 90.0 93.0 92.0 139.0 91.0 92.0 145.0 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:19:27 INFO - 2016-03-06 02:19:27,776 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 145.0 146.0 145.0 147.0 145.0 145.0 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.50 stddev:2.57 (4.3%) stddev-sans-first:2.46 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: Values: 56.0 60.0 60.0 61.0 58.0 59.0 65.0 59.0 57.0 57.0 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 65.0 63.0 64.0 64.0 64.0 65.0 64.0 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:78.50 stddev:5.53 (7.0%) stddev-sans-first:5.57 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: Values: 76.0 85.0 78.0 78.0 79.0 85.0 78.0 78.0 94.0 78.0 02:19:27 INFO - 2016-03-06 02:19:27,777 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:117.50 stddev:4.46 (3.8%) stddev-sans-first:4.36 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: Values: 122.0 118.0 117.0 112.0 111.0 116.0 124.0 122.0 116.0 113.0 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.60 Median:148.00 stddev:6.38 (4.3%) stddev-sans-first:6.75 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: Values: 148.0 149.0 146.0 139.0 145.0 156.0 145.0 148.0 155.0 135.0 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:1.51 (2.3%) stddev-sans-first:1.59 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: Values: 66.0 67.0 66.0 69.0 65.0 64.0 64.0 65.0 65.0 65.0 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:19:27 INFO - 2016-03-06 02:19:27,778 DEBUG : BROWSER_OUTPUT: 02:19:28 INFO - 2016-03-06 02:19:28,461 INFO : Browser exited with error code: 0 02:19:28 INFO - 2016-03-06 02:19:28,510 INFO : Completed test kraken (00:00:57) 02:19:28 INFO - 2016-03-06 02:19:28,510 INFO : Starting test v8_7 02:19:28 INFO - 2016-03-06 02:19:28,510 DEBUG : operating with platform_type : linux_ 02:19:28 INFO - 2016-03-06 02:19:28,510 INFO : Initialising browser for v8_7 test... 02:19:28 INFO - 2016-03-06 02:19:28,517 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpV8SEfe/profile http://localhost:36104/getInfo.html 02:19:32 INFO - 2016-03-06 02:19:32,708 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:19:32 INFO - 2016-03-06 02:19:32,709 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:19:32 INFO - 2016-03-06 02:19:32,709 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 02:19:32 INFO - 2016-03-06 02:19:32,709 DEBUG : BROWSER_OUTPUT: __metrics 02:19:32 INFO - 2016-03-06 02:19:32,886 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] 02:19:33 INFO - 2016-03-06 02:19:33,733 INFO : Browser initialized. 02:19:33 INFO - 2016-03-06 02:19:33,734 INFO : Running cycle 1/1 for v8_7 test... 02:19:33 INFO - 2016-03-06 02:19:33,734 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpV8SEfe/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:19:45 INFO - 2016-03-06 02:19:45,367 DEBUG : BROWSER_OUTPUT: RSS: Main: 132702208 02:19:45 INFO - 2016-03-06 02:19:45,368 DEBUG : BROWSER_OUTPUT: 02:20:06 INFO - 2016-03-06 02:20:06,737 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:36104/tests/v8_7/run.html (next: http://localhost:36104/tests/v8_7/run.html) 02:20:06 INFO - 2016-03-06 02:20:06,995 DEBUG : BROWSER_OUTPUT: RSS: Main: 428199936 02:20:06 INFO - 2016-03-06 02:20:06,995 DEBUG : BROWSER_OUTPUT: 02:20:06 INFO - 2016-03-06 02:20:06,995 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:20:06 INFO - 2016-03-06 02:20:06,995 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:20:06 INFO - 2016-03-06 02:20:06,995 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:20:06 INFO - 2016-03-06 02:20:06,996 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:20:06 INFO - 2016-03-06 02:20:06,996 DEBUG : BROWSER_OUTPUT: |0;Richards;168.12373907195695 02:20:06 INFO - 2016-03-06 02:20:06,996 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.74464579901152 02:20:06 INFO - 2016-03-06 02:20:06,996 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.1641168289291 02:20:06 INFO - 2016-03-06 02:20:06,996 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5148.717948717948 02:20:06 INFO - 2016-03-06 02:20:06,996 DEBUG : BROWSER_OUTPUT: |4;RayTrace;958.7727708533077 02:20:06 INFO - 2016-03-06 02:20:06,996 DEBUG : BROWSER_OUTPUT: |5;Earley;542.8881650380022 02:20:06 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: |6;Boyer;10489.583333333334 02:20:07 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: |7;RegExp;24585.365853658535 02:20:07 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: |8;Splay;513.874614594039 02:20:07 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 02:20:07 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:20:07 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: __startTimestamp1457259606988__endTimestamp 02:20:07 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:06,997 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:20:07 INFO - 2016-03-06 02:20:06,998 DEBUG : BROWSER_OUTPUT: Number of tests: 10 02:20:07 INFO - 2016-03-06 02:20:06,998 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:06,998 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.12 Median:168.12 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:06,998 DEBUG : BROWSER_OUTPUT: Values: 168.1 02:20:07 INFO - 2016-03-06 02:20:06,998 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:06,998 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:06,998 DEBUG : BROWSER_OUTPUT: Values: 164.7 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.16 Median:278.16 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: Values: 278.2 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5148.72 Median:5148.72 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: Values: 5148.7 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:06,999 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:958.77 Median:958.77 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:07,000 DEBUG : BROWSER_OUTPUT: Values: 958.8 02:20:07 INFO - 2016-03-06 02:20:07,000 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:07,000 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.89 Median:542.89 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:07,000 DEBUG : BROWSER_OUTPUT: Values: 542.9 02:20:07 INFO - 2016-03-06 02:20:07,000 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:07,000 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10489.58 Median:10489.58 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:07,000 DEBUG : BROWSER_OUTPUT: Values: 10489.6 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24585.37 Median:24585.37 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: Values: 24585.4 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:513.87 Median:513.87 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: Values: 513.9 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: Values: 4845.4 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:20:07 INFO - 2016-03-06 02:20:07,001 DEBUG : BROWSER_OUTPUT: 02:20:07 INFO - 2016-03-06 02:20:07,726 INFO : Browser exited with error code: 0 02:20:07 INFO - 2016-03-06 02:20:07,738 INFO : Completed test v8_7 (00:00:39) 02:20:08 INFO - 2016-03-06 02:20:08,024 INFO : Completed test suite (00:20:07) 02:20:08 INFO - 2016-03-06 02:20:08,024 DEBUG : Working with test: dromaeo_css 02:20:08 INFO - 2016-03-06 02:20:08,025 DEBUG : Generating results file: dromaeo_css 02:20:08 INFO - 2016-03-06 02:20:08,026 DEBUG : Working with test: kraken 02:20:08 INFO - 2016-03-06 02:20:08,027 DEBUG : Generating results file: kraken 02:20:08 INFO - 2016-03-06 02:20:08,027 INFO : javascript benchmark 02:20:08 INFO - 2016-03-06 02:20:08,027 DEBUG : Working with test: v8_7 02:20:08 INFO - 2016-03-06 02:20:08,027 DEBUG : Generating results file: v8_7 02:20:08 INFO - 2016-03-06 02:20:08,028 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:20:08 INFO - 2016-03-06 02:20:08,082 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 02:20:08 INFO - 2016-03-06 02:20:08,082 DEBUG : process_Request line: dromaeo_css 7300.28 graph.html#tests=[[72,53,35]] 02:20:08 INFO - 2016-03-06 02:20:08,082 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:20:08 INFO - 2016-03-06 02:20:08,111 DEBUG : process_Request line: kraken 1486.50 graph.html#tests=[[232,53,35]] 02:20:08 INFO - 2016-03-06 02:20:08,111 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 02:20:08 INFO - 2016-03-06 02:20:08,215 DEBUG : process_Request line: v8_7 23289.14 graph.html#tests=[[230,53,35]] 02:20:08 INFO - 2016-03-06 02:20:08,216 INFO : javascript benchmark 02:20:08 INFO - 2016-03-06 02:20:08,219 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3246.6104067511897, 3337.1990722226674, 3366.9158271043207, 3367.3265346930607, 3371.2583231689036, 5401.162819762933, 5435.374931882835, 5442.102053161224, 5451.264079349292, 5482.122860342346, 5437.592180586452, 5510.421405752399, 5545.694986775726, 5553.611247212697, 5553.8333849984565, 4854.951450485485, 4928.531789479994, 5008.549230569239, 5063.164577844651, 5080.085584594772, 4964.329815474919, 4985.152524070917, 5038.319826823368, 5039.244113382985, 5044.571211447032, 4901.6078713703, 4990.176620857573, 4998.450170481244, 5049.570786483153, 5063.316452278932, 4997.42529609096, 5092.898142037157, 5093.79624815007, 5096.159133742928, 5123.948760512384, 4732.929006064912, 5005.124103281911, 5007.0, 5036.043151801146, 5068.518490743373, 5170.819021334235, 5216.217567364888, 5262.552683021948, 5268.130758424893, 5269.0252303324005, 5830.941690583082, 5840.065589505702, 5866.413358664142, 5872.853178670524, 5879.588428809985, 5603.859903502404, 5734.799282025109, 5869.706514674292, 5870.236869206976, 5880.882382352372, 5363.383210930754, 5421.376541697716, 5424.62027658068, 5426.972865135649, 5461.863453413656, 5942.078977758454, 6016.037434010537, 6022.728977196037, 6023.45788879003, 6041.607295525777, 5419.0, 5592.664440133605, 5659.547236221092, 5800.1299805029585, 5823.970880145572, 5849.268841394825, 5856.882862342729, 5861.3552509223955, 5864.266966629171, 5895.55783316253, 5783.0, 5826.446487583673, 5828.154917536934, 5832.766689332379, 5836.649801011953, 5513.807016754395, 5617.157426386075, 5728.971355143198, 5838.8906107839375, 5840.328362238397, 4460.0633866887665, 4474.664400170001, 4484.977575112103, 4488.259437192892, 4508.639308855284, 4037.95013296545, 4044.373122166069, 4044.737092089005, 4077.041895154636, 4077.960120169397, 5374.5700343972385, 5502.119660854244, 5561.805336813192, 5651.519620832193, 5680.943190568124, 5935.6735379553675, 5991.0, 5994.550408719363, 5996.37038110992, 5997.13041608973, 5338.599605029561, 5653.265075540154, 5656.971715141398, 5658.858528536737, 5660.688662123623, 5011.348524691767, 5093.617978651616, 5200.167973124282, 5214.426413094557, 5221.582273418117, 6016.368281330398, 6037.0642550404755, 6056.031035034462, 6056.485198758155, 6060.757569697251, 5842.532597392198, 5921.644701317935, 5922.911156332572, 5929.110633405024, 5932.347441781314, 5813.331466881363, 5816.389279125631, 5891.675957822361, 5918.881622367442, 5924.11138329254, 5713.800016999385, 5774.2782152230975, 5810.593258471867, 5834.329052158886, 5834.94165058344, 5762.164486149569, 5906.793262235801, 5921.496672782861, 5924.674142922008, 5925.822225333247, 5577.470140336662, 5581.246531718329, 5667.0, 5674.659520428788, 5703.600747947604, 5780.277465316835, 5799.5070419014855, 5817.738201781015, 5848.4151584841175, 5855.03391940325, 5516.641418307797, 5565.693886836263, 5744.109663002074, 5749.482546570829, 5750.281214848144, 5703.914441283301, 5802.245708057925, 5802.4197580241635, 5808.419158084158, 5846.064629659234, 5689.089745640677, 5693.259876216065, 5713.314402271754, 5721.427857214246, 5733.0540460823495], "unit": "ms", "name": "dojo.html", "value": 5407.930308705232}, {"lowerIsBetter": false, "replicates": [605.639644411575, 642.3608509533016, 654.2345455816695, 657.3328072006914, 658.1805651963307, 32659.0, 33463.16342091446, 40496.987575310595, 41036.794816025955, 41039.79480102603, 43830.12339753203, 44337.77831110848, 44347.33478997809, 44360.55639443613, 44432.3335149973, 7972.362211023134, 8100.392470564701, 9041.321900857445, 9129.543522823866, 9216.400933939307, 10951.123910087173, 11200.103991680646, 11203.495842687058, 11207.383593902332, 11208.607698730546, 10979.890201097967, 11040.723981900437, 11090.833637495443, 11096.833547496793, 11098.223124381297, 10762.784744305149, 10852.240343175981, 10857.782844343068, 10858.077063449577, 10859.565617375334, 10751.83872241917, 10891.727706807314, 10892.836607450894, 10893.782124357469, 10900.073493752961, 42575.57424425778, 43061.13877722428, 43102.0, 43126.78436607797, 43164.352534712, 3520.2783429396914, 3579.158897659048, 3579.928401431983, 3763.7929913854728, 3796.52543432071, 32034.679653203402, 32060.679393206003, 32090.679093209004, 32323.83838080818, 32604.18489537757, 3681.3189559931498, 3712.684421824148, 3716.8141592920247, 3803.1633040730994, 3825.8660946866726, 284928.0, 285922.0, 286490.567547163, 287574.0, 288819.0, 63142.0, 64203.35796641974, 69379.0, 70807.29192708006, 71014.64492677503, 63426.0, 63502.68248658819, 63661.0, 63673.68163159154, 63682.0, 16145.757813632805, 16341.0, 16350.0, 16352.509424717278, 16361.67276654484, 11251.437428128724, 11266.380349080879, 11274.154438417152, 11413.942930285462, 11428.0, 21136.674965753755, 21550.461238469165, 21557.0, 21572.67640985355, 21628.35114946554, 7914.683412663544, 7918.406119540942, 7986.560739159286, 8089.555074470961, 8125.715599954092, 1063.4735805776036, 1068.5885933915342, 1073.8872418395956, 1083.3554035348955, 1123.3260043973714, 29987.250318741768, 30238.84880575583, 30400.39199216003, 30642.233944151576, 30709.539356909663, 1111.7220694826294, 1112.1603189591967, 1157.2998336006763, 1193.612076075279, 1211.8242854786183, 12930.418131184118, 14001.29993500321, 14548.927255363655, 14583.854161458461, 15133.924330378497, 361336.0, 373827.0, 379177.0, 380085.0, 381245.0, 57265.14102288469, 57283.0, 57568.13647795287, 57994.0, 58061.41938580645, 1270.720441502868, 1353.1069494133824, 1423.6156237815733, 1448.5437087317462, 1471.7350876842056, 26438.603420948315, 28716.282092947844, 28718.569221462112, 29033.274168145967, 29781.702182977893, 44495.33257001212, 44850.775746121064, 44857.10285794331, 44864.55135448604, 47245.527544724115, 195189.0, 387940.0, 395349.0, 397139.0, 397684.0, 33997.3200535983, 34337.701527286365, 34682.82658586691, 34685.959421217405, 34701.0, 46898.296525551465, 47047.0, 47215.527844722485, 47275.05449890914, 48014.0, 38854.71580262336, 39895.40156897707, 40078.79960600178, 40747.796261018506, 40945.59054409418, 36968.445473317384, 38225.808870955465, 38872.0, 41195.0, 42080.15839683251, 34256.0, 34479.0, 34962.300753985284, 35075.29849403049, 35151.29697406089, 30636.234094146934, 32336.67663323337, 33135.33729325352, 33186.33627327488, 33545.16137096592, 25509.23472295834, 26182.0, 26450.603240951787, 27051.72948270571, 27223.86388068047], "unit": "ms", "name": "ext.html", "value": 19913.097789095365}, {"lowerIsBetter": false, "replicates": [375.93984962406006, 378.48148037189054, 383.82152299180893, 383.9980800096, 384.4041735310269, 14751.409943602242, 16779.0, 17020.234089465972, 18501.72247416288, 18511.5372115697, 16905.7464138038, 16975.83024169761, 17147.0, 17202.56993575158, 17266.913665431686, 5367.570594352453, 5568.888622227553, 5587.0, 5587.106063029916, 5590.5527557795485, 4225.915481690365, 4346.565343465644, 4349.608535231829, 5580.107182850745, 5940.287165540141, 5972.850678733023, 6024.879502409949, 6033.969830150865, 6048.062550304688, 6051.485623721989, 5851.327097383792, 5995.850103747398, 6000.489958353545, 6007.309159446676, 6012.5791194616395, 5231.738413079331, 5334.0, 5334.786608535654, 5335.253064570963, 5354.892902141974, 20362.389128326173, 21220.25729099474, 21220.575588488296, 21445.142194312208, 21767.0, 2345.7888790008983, 2372.074890792592, 2400.5078958813574, 2401.1595941420537, 2467.568175569268, 13642.249676267802, 13898.722025559433, 13934.790978135235, 13951.930240348733, 14300.0, 1917.5206198450387, 1941.0973897137899, 1959.9020048997493, 1993.9700904486444, 2009.3770931011434, 61206.38793612007, 61835.690821545606, 63198.0, 63344.0, 63475.0, 10013.052303508235, 10629.255952083435, 10636.095931845879, 10639.0, 10700.197485188643, 13923.860761392256, 14060.929695351662, 14070.085444446078, 14096.506622268376, 14102.647433814032, 7979.72070977513, 7988.041435027834, 8004.559749213733, 8032.795080737819, 8059.113497515269, 7439.256074392626, 7442.441816863757, 7475.0, 7482.6632801524065, 7516.699332026657, 6257.7184026718905, 6311.936880631227, 6319.968400158062, 6336.366363363627, 6376.489880809524, 1742.555648309673, 1815.6277963017549, 1823.8358547730768, 1912.3975947576469, 1921.1450904347432, 443.67168295461124, 446.33718927392795, 457.732226647415, 961.8557216417594, 1094.6715985204407, 10984.725381865517, 16896.746548801777, 16902.83097169037, 17032.914835425745, 18785.24859005652, 920.2867777472512, 975.7511834482161, 994.264244459095, 1012.8885822559513, 1026.3636545341787, 9888.258380621339, 10199.796004080024, 10283.07452329294, 10324.174066074696, 10344.120749736207, 687218.0, 730500.0, 745142.0, 762420.0, 781661.0, 79688.0, 82116.0, 82826.0, 83789.58105209557, 85252.14747852566, 1153.7461758413135, 1178.263585259213, 1189.4469071881617, 1198.1553005131361, 1212.9999999999823, 16074.43739469113, 16113.516594502184, 16115.597110072342, 16213.918930405744, 16299.348026078824, 36434.27131457303, 36746.63253367432, 37053.0, 37467.62532374641, 38144.61855381522, 49100.0, 49878.0, 50630.49369506258, 51079.0, 51636.74181629218, 9580.616775328908, 9874.358166719141, 10122.291439599157, 10129.797404052026, 10138.949305253427, 11633.360165190657, 11722.824157637813, 17630.1184940755, 17774.555636109202, 17859.196336164645, 14605.342759575633, 14864.0, 14867.628309292355, 15065.849341506444, 15248.3900643973, 14429.20639364803, 14530.709385812435, 14554.85445145535, 15174.165420901956, 15342.30959606798, 15572.221388930735, 15756.557733551317, 15989.760153597472, 16002.119883406524, 16090.356385744439, 9582.952085239529, 9909.306348555532, 10010.949945250228, 10166.237532185263, 10177.796444071226, 10810.945945270223, 14240.857591424368, 14328.928355358574, 14845.109293441996, 15174.468893588672], "unit": "ms", "name": "jquery.html", "value": 9366.175835324193}, {"lowerIsBetter": false, "replicates": [1511.5389806109133, 1520.0195873661496, 1702.7701260329868, 1824.0697244405353, 1856.0905156473327, 7642.923570764291, 7987.7204297849585, 7990.640421181046, 7996.200379962008, 8012.639431225594, 6901.378875901168, 7043.26045765195, 7404.888926666104, 7704.807379815507, 7796.493227940194, 2275.465265662572, 2285.02886273334, 2311.503026849227, 2311.52613714189, 2311.7688231176917, 1993.372087792348, 2326.174208156097, 2329.848559843613, 2403.0868270057354, 2403.9879800600975, 2026.493376655836, 2287.1537531113513, 2325.6046472099697, 2328.429534763985, 2330.941726456835, 2275.0217406515276, 2311.6879221305076, 2315.9189428369928, 2321.454458202321, 2322.988385058064, 2292.633178691418, 2302.5855346037706, 2327.034280773477, 2330.953380932389, 2371.395294199985, 7872.409569282269, 7932.762017139495, 7954.0, 7954.125046244909, 7957.442978991473, 4706.835260765891, 4796.184648609745, 4806.3511425957395, 4806.831760888352, 4806.95193048072, 2941.970580294191, 2987.790854640176, 2991.5811786349927, 2997.925051873699, 3094.8452577371268, 1009.3893194617224, 1028.0850043461326, 1036.0778906772978, 1043.4608951796115, 1072.2119242356862, 10843.0, 10902.291351062235, 10905.0, 10915.290506117077, 10918.1811364148, 4209.095044565433, 4225.049363893099, 4280.0, 4283.721558098714, 4287.935680964788, 9363.859542106873, 9367.0, 9375.34372593912, 9376.390534615228, 9383.202427793578, 4820.831270905502, 4824.975875120602, 4825.638077144228, 4829.034193161382, 4831.9275210871865, 4347.369631403492, 4386.144701783185, 4387.692861499664, 4387.934180987288, 4389.275769497997, 3761.2101458693983, 3774.641409066134, 3805.2009078093356, 3829.5213098362706, 3839.0594304395045, 1057.5769692123058, 1159.5014143918027, 1194.8864857838491, 1226.693326668333, 1232.4577186038114, 1124.8144056230794, 1127.464454384161, 1182.2492717124517, 1386.4939297156466, 1458.5332693537966, 7468.253174682597, 7492.962535187398, 7539.170691223961, 7592.696292148252, 7602.429817763579, 1174.448009435564, 1188.6374655730085, 1200.087933170779, 1212.9514819407125, 1213.5327898759042, 5971.731272092767, 6056.66688332146, 6080.027195648675, 6083.026715725463, 6092.72582733778, 9078.137576930181, 9194.264458843276, 9331.486768227813, 9341.0, 9354.064593540592, 9133.680321188727, 9245.0, 9263.166315031545, 9286.90713092874, 9288.72133835986, 7268.418526517866, 7420.109586849505, 7445.851082978418, 7506.624668766649, 7511.248875112555, 7479.065116860393, 7527.360174385239, 7529.360004399688, 7574.545527268381, 7580.469367144247, 8373.037100733494, 8495.830083398174, 8541.444806087584, 8555.058943516333, 8556.016058153393, 8477.915220847712, 8659.0, 8705.564721764013, 8714.30285577165, 8728.127187281476, 6884.14667659289, 6916.0, 6947.0, 6997.230304666585, 6998.405135563534, 7564.546127232384, 7605.35354494852, 7693.615319233904, 7699.230076992256, 7721.884171737541, 6258.123862659322, 7151.249118842655, 7154.678039488132, 7268.781936541912, 7344.742933997284, 6312.817673495251, 6544.869102618016, 7143.428525718034, 7168.820779480555, 7216.0, 7266.0, 7305.780826575211, 7314.0, 7324.4140468763435, 7336.633168341668, 6758.628275444898, 6787.219469760843, 6806.285340039224, 6809.0, 6830.829229269308, 6848.486363522656, 6899.6205208712, 6920.550164239308, 6924.757633482804, 6940.479464040116], "unit": "ms", "name": "mootools.html", "value": 4587.365767513048}, {"lowerIsBetter": false, "replicates": [360.29382410475466, 365.9835307411166, 366.83492428407214, 372.1886287892394, 374.6085340818842, 6047.969760151204, 6111.602745821524, 6124.295705993812, 6168.53735969803, 6194.938050619492, 5746.396628354015, 5852.385499522553, 5902.822915312537, 5914.6155499892475, 6037.094435834627, 4710.3641008463765, 4716.151092803286, 4727.030958653468, 4731.266653668687, 4740.6918550294295, 4716.481187069419, 4719.457262414815, 4721.669483136182, 4723.811047558093, 4730.716157030589, 4688.0, 4690.413698287714, 4693.20215563355, 4695.1079294934025, 4710.811567537295, 4624.8150073997, 4694.530546945278, 4697.694649847749, 4701.200795864711, 4714.622830173578, 4159.729617574848, 4162.5629308922735, 4163.10493243951, 4163.5836416358425, 4182.498100227992, 5747.252857128588, 5756.309242890838, 5761.53907687384, 5768.769249230026, 5770.509506691937, 5491.203775452577, 5499.532539734128, 5500.037493438632, 5502.972485137589, 5507.311586051744, 6203.472704820096, 6421.903671444932, 6422.550421470452, 6432.549721519449, 6434.7104380303, 4498.055408364247, 4527.026689261792, 4529.116822219668, 4531.932021019687, 4537.591616754508, 6677.5325727199115, 6747.426468750162, 6749.156355455568, 6749.628770417625, 6750.8312292192595, 4512.909741805145, 4627.490975992638, 4651.069786042805, 4652.604528615038, 4660.627149828006, 4697.95302046982, 4699.859004229878, 4700.95299047012, 4705.835295764632, 4709.411323584552, 5419.810306639249, 5423.593230507728, 5426.158945363475, 5430.049741295257, 5434.83695489136, 6505.544611877218, 6509.641969691617, 6512.674366281667, 6514.5114116441455, 6517.152770139852, 1682.3522943666533, 1710.9828901710823, 1714.5199344183648, 1715.682598719241, 1721.440531827136, 1127.1715289262386, 1164.173436859839, 1183.402381797187, 1187.1808452167977, 1193.3317342288346, 6194.163787888668, 6221.035739460391, 6237.812865614039, 6241.500679945593, 6244.718987645567, 6589.9341006590275, 6592.406683398517, 6599.274079851212, 6600.438962688226, 6612.7354905804195, 6124.663143527148, 6232.439080482778, 6240.500759939194, 6241.407066328691, 6252.656103914237, 3561.1809283864336, 3600.225951420457, 3602.1354874829854, 3605.0446631642635, 3606.098475381155, 6753.45972322213, 6770.72917083321, 6780.32196780318, 6781.084553585301, 6788.49086318528, 6651.035599837996, 6722.058911752359, 6755.628440435725, 6766.018927255521, 6770.69531871067, 6695.899561506581, 6743.966280168666, 6752.831179220559, 6756.324367563205, 6759.121314229217, 6572.769953051811, 6576.901346479711, 6577.210734711865, 6580.8354791130605, 6583.63789991555, 6370.522210834299, 6480.157579514633, 6480.54636175482, 6490.415862572391, 6490.870182596227, 6733.494987875831, 6733.595984240961, 6747.730090796314, 6748.493862960396, 6752.493562982698, 4761.595264402426, 4767.094252092092, 4768.284757286435, 4770.141374552475, 4771.594414474808, 6419.486441084796, 6555.934440655532, 6581.2102547694585, 6581.2102547694585, 6581.407673309425, 6382.87234255303, 6390.041493775971, 6391.360863913572, 6392.552521323462, 6410.61536307823, 6361.0, 6369.172007638977, 6383.96808015957, 6386.0, 6404.295527492065, 6285.497160227263, 6303.653299068412, 6305.90541141874, 6308.873822523432, 6366.9044964326495, 6611.30581288977, 6613.735450581923, 6621.702023408862, 6623.0, 6623.039659249478, 6445.838854028687, 6451.0, 6453.128827608401, 6464.191976003, 6472.093906853139], "unit": "ms", "name": "prototype.html", "value": 4902.880193395435}, {"lowerIsBetter": false, "replicates": [914.1361413464277, 923.2429407885537, 928.809594033223, 930.581238442701, 940.5720397219266, 13759.174449533022, 14012.15927044378, 14125.293735313231, 15306.923465382657, 15432.0, 14208.360623771929, 14277.286135693199, 14298.142111473318, 14299.213543255142, 14304.928475357634, 722.9819254518653, 763.8052296664371, 830.4270053662983, 893.4014210478962, 912.6075787411411, 899.235649697755, 906.9410488318238, 913.0002647101413, 916.5005072235648, 916.7983043730402, 948.6869333120054, 955.5317894231846, 962.1004360922525, 962.1869520255408, 962.6245764151986, 754.9849003019937, 792.5046845721424, 878.5124256037897, 934.486032682029, 939.229831538139, 693.6774399904069, 696.2028477393407, 696.3210869402343, 696.5437638346891, 697.2504557600599, 13668.384922678504, 13682.384292706853, 13713.17720936747, 13755.449782008707, 13816.654583635389, 11824.11319151067, 12562.120651554394, 12903.579673795492, 13180.34098295091, 13269.203847769068, 10918.945405272923, 10930.289531180448, 11064.114870810316, 11107.222494425394, 11601.709957251052, 3768.7550309229814, 3789.412641040643, 3800.2209547043067, 3810.4474851146706, 3875.6318149775725, 44439.0, 45469.317960230626, 45488.0, 45834.54165458336, 49347.50652493501, 10188.184945204366, 10539.789204215873, 10809.0, 10818.67543973682, 10967.177461690406, 17172.313107475562, 17191.398301059402, 17214.397496087826, 17222.569435763955, 17247.310107595807, 7844.058712954368, 7845.37237021037, 7925.484843485155, 8129.75610731679, 8165.38759593021, 4335.393044973706, 4355.0, 4379.627731642782, 4402.537733537996, 4561.680682352204, 3124.3907438049364, 3132.8433578321446, 3153.495440729472, 3153.984230078881, 3155.5266709993684, 588.2147333309994, 591.1280860730388, 605.6214865708932, 606.8149214489623, 612.6446660936646, 649.6524359467662, 687.3058211206617, 701.8841891087914, 748.2480107491936, 751.6279441676405, 8722.47665140081, 9047.77380565478, 9475.147236748726, 9611.327207095435, 9951.25365597583, 706.8480276740526, 766.5975362934392, 776.1811289090065, 788.4993029426241, 788.7712563356562, 4303.892402689895, 4376.256036473807, 4443.466783985878, 4452.710573812692, 4493.640508759357, 57673.0, 57695.13457298144, 60052.09921851176, 62969.685151574864, 63234.0, 51976.22035669468, 52467.0, 52762.0, 53924.73037634787, 54282.45717542774, 49054.0, 49204.0, 49984.0, 50286.49713502891, 50432.24351634655, 9237.861432078658, 9408.717738467856, 9472.336936414385, 9573.808523829624, 9690.467024313448, 35369.646303537666, 36238.0, 36756.632433676394, 36759.81620091882, 36924.44613330856, 42489.0, 45825.54174458346, 47758.0, 48400.0, 48555.27167092567, 11362.54549817998, 11584.594539191088, 11648.0, 11715.121365897421, 11931.4034298283, 12107.939460302641, 12407.93796031014, 12490.250584964568, 12528.248305101723, 12850.866062587495, 11435.714107147387, 11458.656240312805, 11692.298462092302, 11702.0, 11876.881231187923, 11988.160828741904, 12204.633860984184, 12279.0, 12335.444904979477, 12519.311437871005, 10943.0, 10975.924418951116, 11019.393933333744, 11152.55389784432, 11220.326780393203, 9461.952690236505, 9470.715878523655, 10161.288709790244, 10221.59113635476, 10340.12108970746, 10204.0, 10321.071103600712, 10362.222833287684, 10385.0, 10407.167426606005], "unit": "ms", "name": "yui.html", "value": 6671.889869308334}], "name": "dromaeo_css", "value": 7300.275828414299}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 104.0, 109.0, 111.0, 109.0, 100.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.1}, {"lowerIsBetter": true, "replicates": [154.0, 120.0, 151.0, 156.0, 124.0, 145.0, 154.0, 119.0, 170.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.6}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 160.0, 158.0, 159.0, 161.0, 160.0, 161.0, 159.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.8}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 71.0, 148.0, 71.0, 71.0, 73.0, 72.0, 151.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.3}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 133.0, 90.0, 93.0, 92.0, 139.0, 91.0, 92.0, 145.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 147.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [56.0, 60.0, 60.0, 61.0, 58.0, 59.0, 65.0, 59.0, 57.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 59.2}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 65.0, 63.0, 64.0, 64.0, 64.0, 65.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.0}, {"lowerIsBetter": true, "replicates": [76.0, 85.0, 78.0, 78.0, 79.0, 85.0, 78.0, 78.0, 94.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.9}, {"lowerIsBetter": true, "replicates": [122.0, 118.0, 117.0, 112.0, 111.0, 116.0, 124.0, 122.0, 116.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.1}, {"lowerIsBetter": true, "replicates": [148.0, 149.0, 146.0, 139.0, 145.0, 156.0, 145.0, 148.0, 155.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.6}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 66.0, 69.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.6}], "name": "kraken", "value": 1486.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.12373907195695], "unit": "score", "name": "Richards", "value": 209.97629600000008}, {"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "DeltaBlue", "value": 401.33626}, {"lowerIsBetter": false, "replicates": [278.1641168289291], "unit": "score", "name": "Encrypt", "value": 956.9206949999998}, {"lowerIsBetter": false, "replicates": [5148.717948717948], "unit": "score", "name": "Decrypt", "value": 51.69850099601596}, {"lowerIsBetter": false, "replicates": [958.7727708533077], "unit": "score", "name": "RayTrace", "value": 771.8085270000001}, {"lowerIsBetter": false, "replicates": [542.8881650380022], "unit": "score", "name": "Earley", "value": 1227.624846}, {"lowerIsBetter": false, "replicates": [10489.583333333334], "unit": "score", "name": "Boyer", "value": 63.5356981132075}, {"lowerIsBetter": false, "replicates": [24585.365853658535], "unit": "score", "name": "RegExp", "value": 37.05395337301591}, {"lowerIsBetter": false, "replicates": [513.874614594039], "unit": "score", "name": "Splay", "value": 158.58148599999993}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 23289.143437159375}]} 02:20:08 INFO - RETURN: dromaeo_css: 7300.28 02:20:08 INFO - RETURN: kraken: 1486.50 02:20:08 INFO - RETURN: v8_7: 23289.14 02:20:08 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23289.14"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1486.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7300.28"}}} 02:20:08 INFO - Return code: 0 02:20:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 02:20:08 INFO - # TBPL SUCCESS # 02:20:08 INFO - Running post-action listener: _resource_record_post_action 02:20:08 INFO - Running post-run listener: _resource_record_post_run 02:20:09 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 438272; Write bytes: 505724928; Read time: 556; Write time: 423760 02:20:09 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 8192; Write bytes: 243347456; Read time: 112; Write time: 279984 02:20:09 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 430080; Write bytes: 262377472; Read time: 444; Write time: 143776 02:20:09 INFO - Running post-run listener: _upload_blobber_files 02:20:09 INFO - Blob upload gear active. 02:20:09 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:20:09 INFO - Copying logs to upload dir... 02:20:09 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.269419 ========= master_lag: 0.42 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-03-06 02:20:09.686082) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-03-06 02:20:09.693366) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009958 build_url: 'https://queue.taskcluster.net/v1/task/iV5_o94sS7qlJUxoIhtgeg/artifacts/public/build/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.12 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-03-06 02:20:09.823784) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 02:20:09.827256) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1457258348.860965-1026825341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004555 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-06 02:20:09.898270) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-06 02:20:09.898619) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-06 02:20:09.900290) ========= ========= Total master_lag: 0.77 =========