builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1448083368.75 results: success (0) buildid: 20151120173025 builduid: 651ab1dec87a48b596125f5f8a8ea728 revision: 809ef65b4a96cfb02ef3acfdb664fa03394a0195 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.746899) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.747375) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.747674) ========= 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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009051 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.777833) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.778136) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.819952) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.820441) ========= 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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004680 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.880577) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.880861) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.881195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:48.881640) ========= 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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-20 21:22:50-- 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% 224M=0s 2015-11-20 21:22:50 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.189093 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.087590) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.087901) ========= 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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019590 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.150840) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.151175) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 809ef65b4a96cfb02ef3acfdb664fa03394a0195 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 809ef65b4a96cfb02ef3acfdb664fa03394a0195 --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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-20 21:22:50,477 truncating revision to first 12 chars 2015-11-20 21:22:50,477 Setting DEBUG logging. 2015-11-20 21:22:50,477 attempt 1/10 2015-11-20 21:22:50,477 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/809ef65b4a96?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-20 21:22:50,736 unpacking tar archive at: fx-team-809ef65b4a96/testing/mozharness/ program finished with exit code 0 elapsedTime=0.545033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.711825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.712132) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.720704) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.721071) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 21:22:49.721512) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-11-20 21:22:49.721831) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:22:51 INFO - MultiFileLogger online at 20151120 21:22:51 in /builds/slave/test-pgo 21:22:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:22:51 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:22:51 INFO - {'append_to_log': False, 21:22:51 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:22:51 INFO - 'blob_upload_branch': 'Fx-Team', 21:22:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:22:51 INFO - 'branch': 'Fx-Team', 21:22:51 INFO - 'buildbot_json_path': 'buildprops.json', 21:22:51 INFO - 'config_files': ('talos/linux_config.py',), 21:22:51 INFO - 'default_actions': ('clobber', 21:22:51 INFO - 'read-buildbot-config', 21:22:51 INFO - 'download-and-extract', 21:22:51 INFO - 'populate-webroot', 21:22:51 INFO - 'create-virtualenv', 21:22:51 INFO - 'install', 21:22:51 INFO - 'run-tests'), 21:22:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:22:51 INFO - 'download_minidump_stackwalk': True, 21:22:51 INFO - 'download_symbols': 'ondemand', 21:22:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:22:51 INFO - 'tooltool.py': '/tools/tooltool.py', 21:22:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:22:51 INFO - '/tools/misc-python/virtualenv.py')}, 21:22:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:22:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:22:51 INFO - 'installer_path': 'installer.exe', 21:22:51 INFO - 'log_level': 'info', 21:22:51 INFO - 'log_name': 'talos', 21:22:51 INFO - 'log_to_console': True, 21:22:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:22:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:22:51 INFO - 'opt_config_files': (), 21:22:51 INFO - 'pip_index': False, 21:22:51 INFO - 'sps_profile': False, 21:22:51 INFO - 'sps_profile_interval': 0, 21:22:51 INFO - 'suite': 'dromaeojs', 21:22:51 INFO - 'system_bits': '64', 21:22:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:22:51 INFO - 'title': 'talos-linux64-ix-066', 21:22:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:22:51 INFO - 'use_talos_json': True, 21:22:51 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:22:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:22:51 INFO - 'work_dir': 'build'} 21:22:51 INFO - ##### 21:22:51 INFO - ##### Running clobber step. 21:22:51 INFO - ##### 21:22:51 INFO - Running pre-action listener: _resource_record_pre_action 21:22:51 INFO - Running main action method: clobber 21:22:51 INFO - rmtree: /builds/slave/test-pgo/build 21:22:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:22:51 INFO - Running post-action listener: _resource_record_post_action 21:22:51 INFO - ##### 21:22:51 INFO - ##### Running read-buildbot-config step. 21:22:51 INFO - ##### 21:22:51 INFO - Running pre-action listener: _resource_record_pre_action 21:22:51 INFO - Running main action method: read_buildbot_config 21:22:51 INFO - Using buildbot properties: 21:22:51 INFO - { 21:22:51 INFO - "properties": { 21:22:51 INFO - "buildnumber": 506, 21:22:51 INFO - "product": "firefox", 21:22:51 INFO - "script_repo_revision": "production", 21:22:51 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:22:51 INFO - "repository": "", 21:22:51 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 21:22:51 INFO - "buildid": "20151120173025", 21:22:51 INFO - "slavename": "talos-linux64-ix-066", 21:22:51 INFO - "pgo_build": "True", 21:22:51 INFO - "basedir": "/builds/slave/test-pgo", 21:22:51 INFO - "project": "", 21:22:51 INFO - "platform": "ubuntu64_hw", 21:22:51 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:22:51 INFO - "slavebuilddir": "test", 21:22:51 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 21:22:51 INFO - "branch": "fx-team", 21:22:51 INFO - "repo_path": "integration/fx-team", 21:22:51 INFO - "stage_platform": "linux64-pgo", 21:22:51 INFO - "builduid": "651ab1dec87a48b596125f5f8a8ea728", 21:22:51 INFO - "revision": "809ef65b4a96cfb02ef3acfdb664fa03394a0195" 21:22:51 INFO - }, 21:22:51 INFO - "sourcestamp": { 21:22:51 INFO - "repository": "", 21:22:51 INFO - "hasPatch": false, 21:22:51 INFO - "project": "", 21:22:51 INFO - "branch": "fx-team-linux64-pgo-talos", 21:22:51 INFO - "changes": [ 21:22:51 INFO - { 21:22:51 INFO - "category": null, 21:22:51 INFO - "files": [ 21:22:51 INFO - { 21:22:51 INFO - "url": null, 21:22:51 INFO - "name": "https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 21:22:51 INFO - } 21:22:51 INFO - ], 21:22:51 INFO - "repository": "", 21:22:51 INFO - "rev": "809ef65b4a96cfb02ef3acfdb664fa03394a0195", 21:22:51 INFO - "who": "sendchange", 21:22:51 INFO - "when": 1448083317, 21:22:51 INFO - "number": 6691945, 21:22:51 INFO - "comments": "", 21:22:51 INFO - "project": "", 21:22:51 INFO - "at": "Fri 20 Nov 2015 21:21:57", 21:22:51 INFO - "branch": "fx-team-linux64-pgo-talos", 21:22:51 INFO - "revlink": "", 21:22:51 INFO - "properties": [ 21:22:51 INFO - [ 21:22:51 INFO - "buildid", 21:22:51 INFO - "20151120173025", 21:22:51 INFO - "Change" 21:22:51 INFO - ], 21:22:51 INFO - [ 21:22:51 INFO - "builduid", 21:22:51 INFO - "651ab1dec87a48b596125f5f8a8ea728", 21:22:51 INFO - "Change" 21:22:51 INFO - ], 21:22:51 INFO - [ 21:22:51 INFO - "pgo_build", 21:22:51 INFO - "True", 21:22:51 INFO - "Change" 21:22:51 INFO - ] 21:22:51 INFO - ], 21:22:51 INFO - "revision": "809ef65b4a96cfb02ef3acfdb664fa03394a0195" 21:22:51 INFO - } 21:22:51 INFO - ], 21:22:51 INFO - "revision": "809ef65b4a96cfb02ef3acfdb664fa03394a0195" 21:22:51 INFO - } 21:22:51 INFO - } 21:22:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 21:22:51 INFO - Running post-action listener: _resource_record_post_action 21:22:51 INFO - ##### 21:22:51 INFO - ##### Running download-and-extract step. 21:22:51 INFO - ##### 21:22:51 INFO - Running pre-action listener: _resource_record_pre_action 21:22:51 INFO - Running main action method: download_and_extract 21:22:51 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:22:51 INFO - https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:22:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/test_packages.json 21:22:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/test_packages.json 21:22:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:22:52 INFO - Downloaded 1302 bytes. 21:22:52 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:22:52 INFO - Using the following test package requirements: 21:22:52 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:22:52 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:22:52 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:52 INFO - u'jsshell-linux-x86_64.zip'], 21:22:52 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:22:52 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:22:52 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:22:52 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:22:52 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:22:52 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:22:52 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:22:52 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:22:52 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:22:52 INFO - https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:22:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:22:53 INFO - Downloaded 22524366 bytes. 21:22:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:22:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:22:54 INFO - Return code: 0 21:22:54 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:22:54 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')]} 21:22:54 INFO - https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:22:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:22:56 INFO - Downloaded 22524366 bytes. 21:22:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:22:56 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:22:56 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:22:57 INFO - Return code: 0 21:22:57 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')]} 21:22:57 INFO - https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:22:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:22:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:22:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:22:59 INFO - Downloaded 11130997 bytes. 21:22:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 21:22:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:22:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 21:22:59 INFO - Return code: 0 21:22: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')]} 21:22:59 INFO - https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:22:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:22:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:22:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:22:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:23:01 INFO - Downloaded 57445965 bytes. 21:23:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:23:01 INFO - mkdir: /builds/slave/test-pgo/properties 21:23:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:23:01 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:23:01 INFO - Contents: 21:23:01 INFO - build_url:https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:23:01 INFO - Running post-action listener: _resource_record_post_action 21:23:01 INFO - Running post-action listener: set_extra_try_arguments 21:23:01 INFO - ##### 21:23:01 INFO - ##### Running populate-webroot step. 21:23:01 INFO - ##### 21:23:01 INFO - Running pre-action listener: _resource_record_pre_action 21:23:01 INFO - Running main action method: populate_webroot 21:23:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:23:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:23:01 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 21:23:01 INFO - '../fennec_ids.txt'], 21:23:01 INFO - 'tests': ['tcheck2']}, 21:23:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:23:01 INFO - '--tppagecycles', 21:23:01 INFO - '7'], 21:23:01 INFO - 'tests': ['tsvgm']}}, 21:23:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:23:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:23:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:23:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:23:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:23:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:23:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'tests': ['damp', 'tps']}, 21:23:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['damp', 'tps']}, 21:23:01 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:23:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:23:01 INFO - 'other': {'tests': ['a11yr', 21:23:01 INFO - 'ts_paint', 21:23:01 INFO - 'tpaint', 21:23:01 INFO - 'sessionrestore', 21:23:01 INFO - 'sessionrestore_no_auto_restore']}, 21:23:01 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['a11yr', 21:23:01 INFO - 'ts_paint', 21:23:01 INFO - 'tpaint', 21:23:01 INFO - 'sessionrestore', 21:23:01 INFO - 'sessionrestore_no_auto_restore']}, 21:23:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['a11yr', 21:23:01 INFO - 'ts_paint', 21:23:01 INFO - 'tpaint', 21:23:01 INFO - 'sessionrestore', 21:23:01 INFO - 'sessionrestore_no_auto_restore']}, 21:23:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['a11yr', 21:23:01 INFO - 'ts_paint', 21:23:01 INFO - 'tpaint', 21:23:01 INFO - 'sessionrestore', 21:23:01 INFO - 'sessionrestore_no_auto_restore']}, 21:23:01 INFO - 'other_l64': {'tests': ['a11yr', 21:23:01 INFO - 'ts_paint', 21:23:01 INFO - 'tpaint', 21:23:01 INFO - 'sessionrestore', 21:23:01 INFO - 'sessionrestore_no_auto_restore']}, 21:23:01 INFO - 'other_nol64': {'tests': ['a11yr', 21:23:01 INFO - 'ts_paint', 21:23:01 INFO - 'tpaint', 21:23:01 INFO - 'sessionrestore', 21:23:01 INFO - 'sessionrestore_no_auto_restore']}, 21:23:01 INFO - 'svgr': {'tests': ['tsvgx', 21:23:01 INFO - 'tsvgr_opacity', 21:23:01 INFO - 'tart', 21:23:01 INFO - 'tscrollx', 21:23:01 INFO - 'cart']}, 21:23:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['tsvgx', 21:23:01 INFO - 'tsvgr_opacity', 21:23:01 INFO - 'tart', 21:23:01 INFO - 'tscrollx', 21:23:01 INFO - 'cart']}, 21:23:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'tests': ['tp5o']}, 21:23:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'talos_options': ['--e10s'], 21:23:01 INFO - 'tests': ['tp5o']}, 21:23:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'talos_options': ['--xperf_path', 21:23:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:23:01 INFO - 'tests': ['tp5n']}, 21:23:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:23:01 INFO - 'talos_options': ['--e10s', 21:23:01 INFO - '--xperf_path', 21:23:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:23:01 INFO - 'tests': ['tp5n']}}, 21:23:01 INFO - 'talos.zip': {'path': '', 21:23:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:23:01 INFO - Running post-action listener: _resource_record_post_action 21:23:01 INFO - ##### 21:23:01 INFO - ##### Running create-virtualenv step. 21:23:01 INFO - ##### 21:23:01 INFO - Running pre-action listener: _resource_record_pre_action 21:23:01 INFO - Running main action method: create_virtualenv 21:23:01 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:23:01 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 21:23:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:23:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:23:01 INFO - Using real prefix '/usr' 21:23:01 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:23:01 INFO - Installing distribute.............................................................................................................................................................................................done. 21:23:03 INFO - Installing pip.................done. 21:23:03 INFO - Return code: 0 21:23:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6222a05c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5f6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20117c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', '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 21:23:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 21:23:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:23:03 INFO - Using env: {'DISPLAY': ':0', 21:23:03 INFO - 'HOME': '/home/cltbld', 21:23:03 INFO - 'LANG': 'en_US.UTF-8', 21:23:03 INFO - 'LANGUAGE': 'en_US:en', 21:23:03 INFO - 'LOGNAME': 'cltbld', 21:23:03 INFO - 'MAIL': '/var/mail/cltbld', 21:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:03 INFO - 'MOZ_NO_REMOTE': '1', 21:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:03 INFO - 'NO_EM_RESTART': '1', 21:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:03 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:03 INFO - 'SHELL': '/bin/bash', 21:23:03 INFO - 'SHLVL': '1', 21:23:03 INFO - 'TERM': 'linux', 21:23:03 INFO - 'TMOUT': '86400', 21:23:03 INFO - 'USER': 'cltbld', 21:23:03 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:03 INFO - '_': '/tools/buildbot/bin/python'} 21:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:03 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 21:23:03 INFO - Cleaning up... 21:23:03 INFO - Return code: 0 21:23:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6222a05c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5f6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20117c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', '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 21:23:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 21:23:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:23:04 INFO - Using env: {'DISPLAY': ':0', 21:23:04 INFO - 'HOME': '/home/cltbld', 21:23:04 INFO - 'LANG': 'en_US.UTF-8', 21:23:04 INFO - 'LANGUAGE': 'en_US:en', 21:23:04 INFO - 'LOGNAME': 'cltbld', 21:23:04 INFO - 'MAIL': '/var/mail/cltbld', 21:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:04 INFO - 'MOZ_NO_REMOTE': '1', 21:23:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:04 INFO - 'NO_EM_RESTART': '1', 21:23:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:04 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:04 INFO - 'SHELL': '/bin/bash', 21:23:04 INFO - 'SHLVL': '1', 21:23:04 INFO - 'TERM': 'linux', 21:23:04 INFO - 'TMOUT': '86400', 21:23:04 INFO - 'USER': 'cltbld', 21:23:04 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:04 INFO - '_': '/tools/buildbot/bin/python'} 21:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:04 INFO - Downloading/unpacking psutil>=0.7.1 21:23:04 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 21:23:04 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 21:23:04 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 21:23:04 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 21:23:04 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 21:23:04 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 21:23:05 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:23:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:23:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:23:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:23:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:23:05 INFO - Installing collected packages: psutil 21:23:05 INFO - Running setup.py install for psutil 21:23:06 INFO - building 'psutil._psutil_linux' extension 21:23:06 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 21:23:06 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 21:23:06 INFO - building 'psutil._psutil_posix' extension 21:23:06 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 21:23:06 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 21:23:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:23:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:23:06 INFO - Successfully installed psutil 21:23:06 INFO - Cleaning up... 21:23:06 INFO - Return code: 0 21:23:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:23:06 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')]} 21:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:06 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')]} 21:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6222a05c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5f6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20117c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', '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 21:23:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 21:23:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:23:06 INFO - Using env: {'DISPLAY': ':0', 21:23:06 INFO - 'HOME': '/home/cltbld', 21:23:06 INFO - 'LANG': 'en_US.UTF-8', 21:23:06 INFO - 'LANGUAGE': 'en_US:en', 21:23:06 INFO - 'LOGNAME': 'cltbld', 21:23:06 INFO - 'MAIL': '/var/mail/cltbld', 21:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:06 INFO - 'MOZ_NO_REMOTE': '1', 21:23:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:06 INFO - 'NO_EM_RESTART': '1', 21:23:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:06 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:06 INFO - 'SHELL': '/bin/bash', 21:23:06 INFO - 'SHLVL': '1', 21:23:06 INFO - 'TERM': 'linux', 21:23:06 INFO - 'TMOUT': '86400', 21:23:06 INFO - 'USER': 'cltbld', 21:23:06 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:06 INFO - '_': '/tools/buildbot/bin/python'} 21:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:23:06 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 21:23:06 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 21:23:06 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 21:23:06 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 21:23:06 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 21:23:06 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 21:23:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:23:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:23:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:23:08 INFO - Installing collected packages: mozsystemmonitor 21:23:08 INFO - Running setup.py install for mozsystemmonitor 21:23:08 INFO - Successfully installed mozsystemmonitor 21:23:08 INFO - Cleaning up... 21:23:08 INFO - Return code: 0 21:23:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6222a05c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5f6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20117c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', '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 21:23:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 21:23:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:23:08 INFO - Using env: {'DISPLAY': ':0', 21:23:08 INFO - 'HOME': '/home/cltbld', 21:23:08 INFO - 'LANG': 'en_US.UTF-8', 21:23:08 INFO - 'LANGUAGE': 'en_US:en', 21:23:08 INFO - 'LOGNAME': 'cltbld', 21:23:08 INFO - 'MAIL': '/var/mail/cltbld', 21:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:08 INFO - 'MOZ_NO_REMOTE': '1', 21:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:08 INFO - 'NO_EM_RESTART': '1', 21:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:08 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:08 INFO - 'SHELL': '/bin/bash', 21:23:08 INFO - 'SHLVL': '1', 21:23:08 INFO - 'TERM': 'linux', 21:23:08 INFO - 'TMOUT': '86400', 21:23:08 INFO - 'USER': 'cltbld', 21:23:08 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:08 INFO - '_': '/tools/buildbot/bin/python'} 21:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:08 INFO - Downloading/unpacking blobuploader==1.2.4 21:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:10 INFO - Downloading blobuploader-1.2.4.tar.gz 21:23:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:23:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:23:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:23:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:23:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:11 INFO - Downloading docopt-0.6.1.tar.gz 21:23:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:23:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:23:11 INFO - Installing collected packages: blobuploader, requests, docopt 21:23:11 INFO - Running setup.py install for blobuploader 21:23:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:23:11 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:23:11 INFO - Running setup.py install for requests 21:23:11 INFO - Running setup.py install for docopt 21:23:11 INFO - Successfully installed blobuploader requests docopt 21:23:11 INFO - Cleaning up... 21:23:11 INFO - Return code: 0 21:23:11 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:23: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')]} 21:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23: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')]} 21:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6222a05c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5f6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20117c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', '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 21:23:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 21:23:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:23:11 INFO - Using env: {'DISPLAY': ':0', 21:23:11 INFO - 'HOME': '/home/cltbld', 21:23:11 INFO - 'LANG': 'en_US.UTF-8', 21:23:11 INFO - 'LANGUAGE': 'en_US:en', 21:23:11 INFO - 'LOGNAME': 'cltbld', 21:23:11 INFO - 'MAIL': '/var/mail/cltbld', 21:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:11 INFO - 'MOZ_NO_REMOTE': '1', 21:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:11 INFO - 'NO_EM_RESTART': '1', 21:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:11 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:11 INFO - 'SHELL': '/bin/bash', 21:23:11 INFO - 'SHLVL': '1', 21:23:11 INFO - 'TERM': 'linux', 21:23:11 INFO - 'TMOUT': '86400', 21:23:11 INFO - 'USER': 'cltbld', 21:23:11 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:11 INFO - '_': '/tools/buildbot/bin/python'} 21:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:23:11 INFO - Running setup.py (path:/tmp/pip-WrbRA6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:23:12 INFO - Running setup.py (path:/tmp/pip-M5beKZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:23:12 INFO - Running setup.py (path:/tmp/pip-DxhAbw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:23:12 INFO - Running setup.py (path:/tmp/pip-Prm2aY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:23:12 INFO - Running setup.py (path:/tmp/pip-rRiqnZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:23:12 INFO - Running setup.py (path:/tmp/pip-Cj7YVj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:23:12 INFO - Running setup.py (path:/tmp/pip-fGcjfI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:23:12 INFO - Running setup.py (path:/tmp/pip-MHz1Y_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:23:12 INFO - Running setup.py (path:/tmp/pip-o1K5QC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:23:12 INFO - Running setup.py (path:/tmp/pip-qwqDOt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:23:12 INFO - Running setup.py (path:/tmp/pip-D2Yppy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:23:12 INFO - Running setup.py (path:/tmp/pip-z0DJwx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:23:12 INFO - Running setup.py (path:/tmp/pip-PVt_JN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:23:12 INFO - Running setup.py (path:/tmp/pip-A6rpXi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:23:12 INFO - Running setup.py (path:/tmp/pip-hqQzts-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:23:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:23:13 INFO - Running setup.py (path:/tmp/pip-98SS8o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:23:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:23:13 INFO - Running setup.py (path:/tmp/pip-3t1KA3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:23:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:23:13 INFO - Running setup.py install for manifestparser 21:23:13 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Running setup.py install for mozcrash 21:23:13 INFO - Running setup.py install for mozdebug 21:23:13 INFO - Running setup.py install for mozdevice 21:23:13 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Running setup.py install for mozfile 21:23:13 INFO - Running setup.py install for mozhttpd 21:23:13 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Running setup.py install for mozinfo 21:23:13 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Running setup.py install for mozInstall 21:23:13 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:23:13 INFO - Running setup.py install for mozleak 21:23:14 INFO - Running setup.py install for mozlog 21:23:14 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:23:14 INFO - Running setup.py install for moznetwork 21:23:14 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:23:14 INFO - Running setup.py install for mozprocess 21:23:14 INFO - Running setup.py install for mozprofile 21:23:14 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:23:14 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:23:14 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:23:14 INFO - Running setup.py install for mozrunner 21:23:14 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:23:14 INFO - Running setup.py install for mozscreenshot 21:23:14 INFO - Running setup.py install for moztest 21:23:14 INFO - Running setup.py install for mozversion 21:23:14 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:23:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:23:14 INFO - Cleaning up... 21:23:14 INFO - Return code: 0 21:23:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:23: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')]} 21:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23: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')]} 21:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6222a05c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5f6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20117c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', '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 21:23:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 21:23:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:23:14 INFO - Using env: {'DISPLAY': ':0', 21:23:14 INFO - 'HOME': '/home/cltbld', 21:23:14 INFO - 'LANG': 'en_US.UTF-8', 21:23:14 INFO - 'LANGUAGE': 'en_US:en', 21:23:14 INFO - 'LOGNAME': 'cltbld', 21:23:14 INFO - 'MAIL': '/var/mail/cltbld', 21:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:14 INFO - 'MOZ_NO_REMOTE': '1', 21:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:14 INFO - 'NO_EM_RESTART': '1', 21:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:14 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:14 INFO - 'SHELL': '/bin/bash', 21:23:14 INFO - 'SHLVL': '1', 21:23:14 INFO - 'TERM': 'linux', 21:23:14 INFO - 'TMOUT': '86400', 21:23:14 INFO - 'USER': 'cltbld', 21:23:14 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:14 INFO - '_': '/tools/buildbot/bin/python'} 21:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:23:15 INFO - Running setup.py (path:/tmp/pip-UN3Kxg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:23:15 INFO - Running setup.py (path:/tmp/pip-Xs_mug-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:23:15 INFO - Running setup.py (path:/tmp/pip-t9TqIy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:23:15 INFO - Running setup.py (path:/tmp/pip-JE9UcF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:23:15 INFO - Running setup.py (path:/tmp/pip-6o5VRO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:23:15 INFO - Running setup.py (path:/tmp/pip-QUOS4E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:23:15 INFO - Running setup.py (path:/tmp/pip-Kr5rt2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:23:15 INFO - Running setup.py (path:/tmp/pip-O_dBUI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:23:15 INFO - Running setup.py (path:/tmp/pip-PznB1D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:23:15 INFO - Running setup.py (path:/tmp/pip-vbSOVx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:23:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:23:15 INFO - Running setup.py (path:/tmp/pip-ysdIvy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:23:15 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)) 21:23:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:23:15 INFO - Running setup.py (path:/tmp/pip-XjBDPN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:23:15 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)) 21:23:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:23:16 INFO - Running setup.py (path:/tmp/pip-c8oF15-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:23:16 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)) 21:23:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:23:16 INFO - Running setup.py (path:/tmp/pip-_BQzPD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:23:16 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)) 21:23:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:23:16 INFO - Running setup.py (path:/tmp/pip-LRtjSH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:23:16 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)) 21:23:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:23:16 INFO - Running setup.py (path:/tmp/pip-Rtj9VK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:23:16 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)) 21:23:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:23:16 INFO - Running setup.py (path:/tmp/pip-MRX0kg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:23:16 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)) 21:23:16 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)) 21:23:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 21:23:16 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)) 21:23:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:23:16 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)) 21:23:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:23:17 INFO - Downloading blessings-1.5.1.tar.gz 21:23:17 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 21:23:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:23:18 INFO - Installing collected packages: blessings 21:23:18 INFO - Running setup.py install for blessings 21:23:18 INFO - Successfully installed blessings 21:23:18 INFO - Cleaning up... 21:23:18 INFO - Return code: 0 21:23:18 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:23:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:23:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:23:18 INFO - Reading from file tmpfile_stdout 21:23:18 INFO - Current package versions: 21:23:18 INFO - argparse == 1.2.1 21:23:18 INFO - blessings == 1.5.1 21:23:18 INFO - blobuploader == 1.2.4 21:23:18 INFO - docopt == 0.6.1 21:23:18 INFO - manifestparser == 1.1 21:23:18 INFO - mozInstall == 1.12 21:23:18 INFO - mozcrash == 0.16 21:23:18 INFO - mozdebug == 0.1 21:23:18 INFO - mozdevice == 0.47 21:23:18 INFO - mozfile == 1.2 21:23:18 INFO - mozhttpd == 0.7 21:23:18 INFO - mozinfo == 0.9 21:23:18 INFO - mozleak == 0.1 21:23:18 INFO - mozlog == 3.0 21:23:18 INFO - moznetwork == 0.27 21:23:18 INFO - mozprocess == 0.22 21:23:18 INFO - mozprofile == 0.27 21:23:18 INFO - mozrunner == 6.11 21:23:18 INFO - mozscreenshot == 0.1 21:23:18 INFO - mozsystemmonitor == 0.0 21:23:18 INFO - moztest == 0.7 21:23:18 INFO - mozversion == 1.4 21:23:18 INFO - psutil == 3.1.1 21:23:18 INFO - requests == 1.2.3 21:23:18 INFO - wsgiref == 0.1.2 21:23:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:23:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6222a05c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5f6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20117c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, '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': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', '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 21:23:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 21:23:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:23:18 INFO - Using env: {'DISPLAY': ':0', 21:23:18 INFO - 'HOME': '/home/cltbld', 21:23:18 INFO - 'LANG': 'en_US.UTF-8', 21:23:18 INFO - 'LANGUAGE': 'en_US:en', 21:23:18 INFO - 'LOGNAME': 'cltbld', 21:23:18 INFO - 'MAIL': '/var/mail/cltbld', 21:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:18 INFO - 'MOZ_NO_REMOTE': '1', 21:23:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:18 INFO - 'NO_EM_RESTART': '1', 21:23:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:18 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:18 INFO - 'SHELL': '/bin/bash', 21:23:18 INFO - 'SHLVL': '1', 21:23:18 INFO - 'TERM': 'linux', 21:23:18 INFO - 'TMOUT': '86400', 21:23:18 INFO - 'USER': 'cltbld', 21:23:18 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:18 INFO - '_': '/tools/buildbot/bin/python'} 21:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 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)) 21:23:18 INFO - Cleaning up... 21:23:18 INFO - Return code: 0 21:23:18 INFO - Running post-action listener: _resource_record_post_action 21:23:18 INFO - Running post-action listener: _start_resource_monitoring 21:23:18 INFO - Starting resource monitoring. 21:23:18 INFO - ##### 21:23:18 INFO - ##### Running install step. 21:23:18 INFO - ##### 21:23:18 INFO - Running pre-action listener: _resource_record_pre_action 21:23:18 INFO - Running main action method: install 21:23:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:23:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:23:18 INFO - Reading from file tmpfile_stdout 21:23:18 INFO - Detecting whether we're running mozinstall >=1.0... 21:23:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:23:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:23:18 INFO - Reading from file tmpfile_stdout 21:23:18 INFO - Output received: 21:23:18 INFO - Usage: mozinstall [options] installer 21:23:18 INFO - Options: 21:23:18 INFO - -h, --help show this help message and exit 21:23:18 INFO - -d DEST, --destination=DEST 21:23:18 INFO - Directory to install application into. [default: 21:23:18 INFO - "/builds/slave/test-pgo"] 21:23:18 INFO - --app=APP Application being installed. [default: firefox] 21:23:18 INFO - mkdir: /builds/slave/test-pgo/build/application 21:23:18 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 21:23:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 21:23:33 INFO - Reading from file tmpfile_stdout 21:23:33 INFO - Output received: 21:23:33 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:23:33 INFO - Running post-action listener: _resource_record_post_action 21:23:33 INFO - ##### 21:23:33 INFO - ##### Running run-tests step. 21:23:33 INFO - ##### 21:23:33 INFO - Running pre-action listener: _resource_record_pre_action 21:23:33 INFO - Running main action method: run_tests 21:23:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:23:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:23:33 INFO - Python 2.7.3 21:23:33 INFO - Return code: 0 21:23:33 INFO - grabbing minidump binary from tooltool 21:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:23:33 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 0x2055d70>, '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 0x2056b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2056fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:23:33 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 21:23:33 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 21:23:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:23:33 INFO - Return code: 0 21:23:33 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:23:33 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:23:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:23:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:23:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:23:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 21:23:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 21:23:33 INFO - Using env: {'DISPLAY': ':0', 21:23:33 INFO - 'HOME': '/home/cltbld', 21:23:33 INFO - 'LANG': 'en_US.UTF-8', 21:23:33 INFO - 'LANGUAGE': 'en_US:en', 21:23:33 INFO - 'LOGNAME': 'cltbld', 21:23:33 INFO - 'MAIL': '/var/mail/cltbld', 21:23:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:23:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:23:33 INFO - 'MOZ_NO_REMOTE': '1', 21:23:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:23:33 INFO - 'NO_EM_RESTART': '1', 21:23:33 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:23:33 INFO - 'PWD': '/builds/slave/test-pgo', 21:23:33 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:23:33 INFO - 'SHELL': '/bin/bash', 21:23:33 INFO - 'SHLVL': '1', 21:23:33 INFO - 'TERM': 'linux', 21:23:33 INFO - 'TMOUT': '86400', 21:23:33 INFO - 'USER': 'cltbld', 21:23:33 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143', 21:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:23:33 INFO - '_': '/tools/buildbot/bin/python'} 21:23:33 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 21:23:33 INFO - mozversion INFO | application_buildid: 20151120173025 21:23:33 INFO - mozversion INFO | application_changeset: 809ef65b4a96cfb02ef3acfdb664fa03394a0195 21:23:33 INFO - mozversion INFO | application_display_name: Nightly 21:23:33 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:23:33 INFO - mozversion INFO | application_name: Firefox 21:23:33 INFO - mozversion INFO | application_remotingname: firefox 21:23:33 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 21:23:33 INFO - mozversion INFO | application_vendor: Mozilla 21:23:33 INFO - mozversion INFO | application_version: 45.0a1 21:23:33 INFO - mozversion INFO | platform_buildid: 20151120173025 21:23:33 INFO - mozversion INFO | platform_changeset: 809ef65b4a96cfb02ef3acfdb664fa03394a0195 21:23:33 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 21:23:33 INFO - mozversion INFO | platform_version: 45.0a1 21:23:33 INFO - 2015-11-20 21:23:33,735 DEBUG : using testdate: 1448083413 21:23:33 INFO - 2015-11-20 21:23:33,735 DEBUG : actual date: 1448083413 21:23:33 INFO - 2015-11-20 21:23:33,744 INFO : starting webserver on 'localhost:42290' 21:23:33 INFO - 2015-11-20 21:23:33,745 INFO : Starting test suite talos-linux64-ix-066 21:23:33 INFO - 2015-11-20 21:23:33,745 INFO : Starting test dromaeo_css 21:23:33 INFO - 2015-11-20 21:23:33,745 DEBUG : operating with platform_type : linux_ 21:23:33 INFO - 2015-11-20 21:23:33,746 INFO : Initialising browser for dromaeo_css test... 21:23:33 INFO - 2015-11-20 21:23:33,763 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNho1Gt/profile http://localhost:42290/getInfo.html 21:23:38 INFO - 2015-11-20 21:23:38,061 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:23:38 INFO - 2015-11-20 21:23:38,061 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:23:38 INFO - 2015-11-20 21:23:38,061 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:23:38 INFO - 2015-11-20 21:23:38,061 DEBUG : BROWSER_OUTPUT: __metrics 21:23:38 INFO - 2015-11-20 21:23:38,230 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:23:39 INFO - 2015-11-20 21:23:39,485 INFO : Browser initialized. 21:23:39 INFO - 2015-11-20 21:23:39,486 INFO : Running cycle 1/1 for dromaeo_css test... 21:23:39 INFO - 2015-11-20 21:23:39,486 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNho1Gt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:23:51 INFO - 2015-11-20 21:23:51,290 DEBUG : BROWSER_OUTPUT: RSS: Main: 140476416 21:23:51 INFO - 2015-11-20 21:23:51,291 DEBUG : BROWSER_OUTPUT: 21:24:42 INFO - 2015-11-20 21:24:42,919 DEBUG : BROWSER_OUTPUT: 1448083482915 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] 21:26:51 INFO - 2015-11-20 21:26:51,193 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42290/tests/dromaeo/cssquery-ext.html) 21:26:53 INFO - 2015-11-20 21:26:53,169 DEBUG : BROWSER_OUTPUT: RSS: Main: 359710720 21:26:53 INFO - 2015-11-20 21:26:53,169 DEBUG : BROWSER_OUTPUT: 21:29:55 INFO - 2015-11-20 21:29:55,697 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-ext.html (next: http://localhost:42290/tests/dromaeo/cssquery-jquery.html) 21:29:56 INFO - 2015-11-20 21:29:56,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 225603584 21:29:56 INFO - 2015-11-20 21:29:56,259 DEBUG : BROWSER_OUTPUT: 21:32:58 INFO - 2015-11-20 21:32:58,170 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42290/tests/dromaeo/cssquery-mootools.html) 21:32:58 INFO - 2015-11-20 21:32:58,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 223109120 21:32:58 INFO - 2015-11-20 21:32:58,733 DEBUG : BROWSER_OUTPUT: 21:36:00 INFO - 2015-11-20 21:36:00,478 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42290/tests/dromaeo/cssquery-prototype.html) 21:36:01 INFO - 2015-11-20 21:36:01,060 DEBUG : BROWSER_OUTPUT: RSS: Main: 204259328 21:36:01 INFO - 2015-11-20 21:36:01,060 DEBUG : BROWSER_OUTPUT: 21:39:03 INFO - 2015-11-20 21:39:03,539 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42290/tests/dromaeo/cssquery-yui.html) 21:39:04 INFO - 2015-11-20 21:39:04,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 203403264 21:39:04 INFO - 2015-11-20 21:39:04,100 DEBUG : BROWSER_OUTPUT: 21:42:06 INFO - 2015-11-20 21:42:06,805 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-yui.html (next: http://localhost:42290/tests/dromaeo/cssquery-dojo.html) 21:42:07 INFO - 2015-11-20 21:42:07,049 DEBUG : BROWSER_OUTPUT: RSS: Main: 230436864 21:42:07 INFO - 2015-11-20 21:42:07,049 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,051 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:42:07 INFO - 2015-11-20 21:42:07,051 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:42:07 INFO - 2015-11-20 21:42:07,051 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:42:07 INFO - 2015-11-20 21:42:07,051 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:42:07 INFO - 2015-11-20 21:42:07,053 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3219.903402897913;3324.667533246677;3353;3375.5949286085674;3379.35792199482;5353.946460535393;5486.698231597261;5494.890102197954;5538.9723051384735;5570.220169176314;5390.299261096062;5462.399136095027;5501.0648189807725;5512.228288039668;5549.417311182328;4969.652124351297;4993.151164302078;5021.297018417425;5043.6721613095215;5099.566536844354;4871.12319782439;5086.79652813887;5091.185410334348;5102.923456148161;5196.532312091911;4985.900281994358;4993.425756038066;5037.370328708911;5058.367704036996;5059.696418214919;5051.545360917498;5066.620003499752;5068.3917929848285;5102.591792656615;5127.8205262816;4852.369192005052;4950.7029578225065;5032.924506132411;5043.571296439807;5046.5710414614805;5243.764030618631;5304.549113325372;5326;5351;5356.758945847446;5993.790217342372;5994.910076348859;5994.910076348859;6000.909986350208;6004.489618382443;5752.050911599623;5807.332156801979;5861.6189947653265;6024.578279520435;6028.5478589106;5506.614536982413;5592.7762889484775;5599.160125981054;5603.495685388294;5638.097904335286;6075.787347442818;6085.452309292096;6097.688996865695;6105.322780187562;6107.328193898667;5773.538116950718;5823.155642431909;5894.646321220741;5951.940480595139;5977.9402205977385;5817.156512305692;5860.2967643883;5914.4381283777975;5915.822525324247;5966.522678185735;5948.434898684618;6056.727447264883;6072.51419886408;6073.817785466443;6079.726412311456;5824.94175058244;5935.881282374328;5949.553783466257;6050.848728781815;6076.969615151985;4522.683412161183;4549.727016379028;4572.565606267399;4586.403767510203;4616.95383046172;4095.877123686294;4110.034141976633;4117.444145040441;4138.482689663792;4142.06803469217;5526.640768350045;5641.069223578145;5666.2350582670815;5674.546036317085;5677.205191273225;5900.970495147497;5959.612625179349;5968.045112781934;5976.5218782497295;6022.156898034191;5601.971990140023;5634.211210430543;5642.153676948408;5647.491725744702;5654.85862853432;5036.269740887624;5069.898602027939;5195.5843532518065;5199.090159222196;5200.193969934666;6094.512439004958;6155.753769849156;6160.10678451622;6165.599236049642;6175;5953.161313390054;5981.52147828164;5981.7308221131025;5983.910241346296;6027.065804800263;5965.552583556338;6082.60463069899;6084.787032453843;6225.875482490415;6233.376662333731;5772.624779389326;5871;5891.764329426775;5904.822855314347;5910.911336329872;5940.702964851654;5974.970125149346;5978.461938425563;5991.161237426851;6007.639541627516;5666.518345940642;5791.623544469596;5835.912461312999;5858.794942177004;5860.941390586039;5754.971225143848;5765.250517432707;5770.596058276048;5773.018586840163;5773.076307790733;5683.3748287687495;5685.829425117253;5691.943080569307;5692.459216374438;5697.173909783141;5702.144678298288;5767.509761670305;5801.419858014165;5807.883842323045;5808.041673123887;5645;5660.801871934463;5687.971560142173;5697.800576979786;5701.914471282851 21:42:07 INFO - 2015-11-20 21:42:07,054 DEBUG : BROWSER_OUTPUT: |1;ext.html;536.7772374464595;596.8209537138857;598.7365559153972;610.6366711806473;618.1068356225253;37081.81459092704;37506;37548.624513754854;37579.62420375796;37583.24833503332;41604;41707.791461042725;42041.78979105108;42050;42070.57929420705;7722.150563438017;7831.765047048598;7847.489913155627;8727.738167854974;8905.154010369037;10486.790264194711;10718.732031699232;10722.73193170173;10741.946290268537;10743.19426043046;10370.170386369073;10458.320209186379;10474.947625261862;10477.318974266687;10494.317869338469;7961.044674639052;8127.634256458474;8136.064352599408;10252.897471025306;10419.479026048743;10311.43287119208;10364.740881477948;10462.319949203353;10465.633702820365;10471.84292235617;39719.8014009931;40290;40318.39522407166;40352.59647403518;40355.394669079986;3483.6168021517615;3514.841832117561;3541.978358442651;3857.652811246973;3936.094698219397;30396.54405183924;30554.84722576395;30563.23591910198;30592;30962.53556196659;3910;3989.7007724420782;4024.215278020787;4078.4697989261504;4186.811593478301;273787;276399;276899;276945;277978.6101069502;55281.44718552763;55818.162727559116;60092.699536502914;63618.68190659017;63921.68039159774;56064;56399;56519.71740141273;56752;57056;15849.683006339808;15932.601684957737;15995.360185592679;16014.279357428944;16016.839831601768;11998.64004079879;12132.211406258564;12162.878371216351;12186.81719774204;12186.939065304618;20331.390058298275;20639.296773549275;20651.380458586267;20669.689954650694;20704.689429658567;7912.604369781488;8045.15525869787;8062.959685201537;8100.513969161869;8111.675532978733;927.1655510041018;937.0535758883576;1004.5529739265957;1095.3427943234155;1149.8792626774236;28333.57499637507;28694.42611147807;28837.134885953456;29014.41971160565;29017.709822901925;1062.4475272858226;1068.8289873620183;1083.0739717541546;1093.6008356949656;1167.7897978363806;14363.54730033693;14404.639884002983;14605;14924.552263432115;16423.507294781175;411852;415115;415264;416629;418435;53091;53187;53518;53587.73206133944;53697.46302537003;1354.6748780292855;1354.8916086713039;1393.958181254564;1411.9082259653296;1418.7375335562954;29874.415070191717;30454.086377408712;30590.235244119074;30742.846285768428;30749.385012299183;43014.78492607517;45661;46193;46204.537954621366;46299.3055104167;205930;384709;391962;392042;393350;32028;32682;32831.83584082064;32899.835500822344;32949;42385.78807105945;42585.574144258164;42892.57107429011;43005;43751.78124109359;35589.46615800713;35962.28075438424;36380.27239455249;36455.27089458249;37087;36635;36734.44898326474;37004.81497592495;37333.4399983995;37368.6263137376;34514.82742586271;40673;40904;41621.58378416177;41655.58344416638;30939.0718278452;31071.68928310688;33733.3253334927;33842.15394615154;33938;25801.612975805;26232.73767262303;26573.7342626579;27109.593356099278;27371.452570948866 21:42:07 INFO - 2015-11-20 21:42:07,055 DEBUG : BROWSER_OUTPUT: |2;jquery.html;358.2369552852426;363.6200170821491;364.42057129164624;364.8777659484073;368.67004031391906;14175.574732758008;14284.92857535711;16817;17294.82705172948;17881.284748610055;14856.405743770263;14959.775603365932;15028;16025.83974160258;17876.731849022275;5328.8401347959625;5500.559955203594;5501.174823776446;5502.257195278647;5506.339239291291;4117.238310912491;4163.084121493267;5428.782848686048;5731.570132240078;5922.141289513018;5814.5057670097885;5848.853778655525;5850.180974663551;5869.295684517842;5885.558583106263;5761.4814666679995;5929.584929054968;5930.466258036798;5935.792247271346;5937.287525496925;5201.973990130063;5242.6330156889035;5254.92117618236;5264;5268.5521730652945;19773.703394449094;19848.10683519245;20187.596248074955;20273.290434834857;20274.391768246976;2063.9277625283194;2082.6043051820257;2128.8296936245065;2136.2309568555306;2163.610550100981;8907.619319005564;8970.686025989058;9649.951750241275;9741.415515069119;10719.78560428787;1764.4971183212722;1827.7898041725107;1828.4423250908467;1831.6977698679702;1901.91441385138;62801;62981;63166;66144.66927665379;66328.6683566579;10463.68608941733;10466.110380617733;10474.47627618616;10511.264211505275;10620.256582039183;13623.25072121023;13643.795343069862;13664.111832730841;13751.862481375058;13758.174509529461;7915.089764677021;7924.48490848093;8000.63997120131;8068.798280043045;8078.798030049178;7772.222777722182;7775.261350171724;7787.727429539939;7827.334676552557;7827.45207835446;3988.720789544762;4182.56083111275;4416.315471101984;4532.161550113239;4549.317602359606;1725.7066298729246;1761.7181251000036;1780.5726625609761;1814.7822261328724;1849.6115815678588;890.2699786175274;921.424109931293;956.6269155029544;991.5736233419555;1027.5530144387214;14426.701051378737;16347;16562.66874662524;16934.153292335333;17874.910625446788;884.9159329863653;914.3553794574809;916.0884919505135;924.2882980105281;975.3952549419405;9740.366876153028;9958.452285124244;10026.19790416765;10036.297459177933;10048.69853904384;554065;554413;560145;573072;575011;78048;78721;80999;82160.58919705363;83038;1172.9882701172878;1175.3887978251262;1186.8813118688236;1190.2798806721983;1194.3669854976877;15498.380064797282;15544.145072021147;15758.921205394357;15761.527154185393;15775.830267499863;36486.63513364832;36690.08274793151;37259.6274037267;37575.43636845395;37611.435828463145;47564.52435475601;47962.52037479581;49024.50975490199;50832.74583627058;52403;9160.954195228982;9389.530523473935;9626.95186524063;9635.710928671871;9683.854742178732;11469.19715619932;11549.42252887369;11613.941930290295;12925.806112908502;17679.38122165718;14229.715405692035;14257.64355891111;14422.134671919719;14647.92676036613;14662.63343416423;14496.420143194155;14679.85320146785;14781.704365912407;14846.480373187318;15251.008684435476;15212.771808422662;15351.462698805488;15422.383104675688;15717;15816.52550423489;9541.570629321835;9576.186024188022;9682.661106861226;9728.951355243178;9742.805143896941;14619.415223390944;14782.186979716218;14820.7776883349;14972;15171.772423413437 21:42:07 INFO - 2015-11-20 21:42:07,057 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1538.2001359293174;1689.096333461598;1742.6950283700357;1757.463973488086;1780.0832571225822;6705.765298214557;7488.625568721569;7649.349805266559;7664.233576642333;7673.769786906388;7552.093748750158;7664.041994750656;7674.270944260286;7676.616169191546;7679.040119985002;2296.5980953333183;2323.082382458936;2324.081987614891;2324.6745455636224;2324.8721320327377;2324.5118525109747;2345.179187284445;2359.905603775847;2363.669086327907;2365.6806331145335;2262.264763951714;2295.2425699519204;2297.4600968772324;2298.6092364298024;2308.2152068296778;2284.5088306014204;2286.0284379138798;2303.355060583031;2307.0310469602805;2315.7684231576877;2336.240721765433;2337.0885354711677;2350.753170917046;2355.4464700795297;2356.2106694257445;6573.408393244583;6687.261312058899;7562.243775622449;7571.621418929031;7603.733869314603;4220.831166753326;4264.893377665552;4289.2922667759785;4428.446444194476;4479.955200448019;2966.362232120083;3028.606281183454;3068.7544996400234;3080.1067690369764;3112.859921303547;976.9267304952086;980.1717548671432;998.236349192868;1012.4886932099316;1045.6758404894506;10540.947295263551;10587.205959552988;10610.204234682353;10616.57533698651;10626.893731062744;4244.427002354704;4259.467566554181;4286.142771445723;4296.591823776736;4367.71609845359;9107.908920910706;9159.313051521163;9207.17135457812;9215.493147876798;9264.90735092654;4731.69243999139;4771.093492236464;4772;4773.164696178155;4784.641151913551;4307.1601037797955;4322.524522302544;4332.610065094157;4356.912861742748;4358.259095953696;3602.2795440911923;3651.94522082169;3662.414013757786;3670.33933891902;3674.210044840319;1143.9428028598536;1150.5915400033034;1151.6775302915198;1153.3541216918384;1157.5369852059243;1037.5901518899993;1062.5643486170634;1062.9043386095288;1230.1696354960366;1383.688670049231;7336.119665640157;7347.632618369167;7377.26227377258;7426.443016773764;7431.108267007994;461.9168549661093;464.4797826434415;465.3043699669017;471.2365967133266;472.95743383095;5397.4062853086125;5752.9712351437975;5847.239858818411;5856.0630299151935;5858.56060795442;8483.830323393497;8495.787605309917;8609;8650.783730406665;8736.519491427905;8594.828103437896;8602.096779838152;8722.389432739647;8757.956210219036;8889.688860889837;7128.358447739728;7151.177614574286;7155.642217889084;7181.999999999895;7229.2047874733735;6970.163580370388;7045.718171273092;7051.083359163276;7053.153621565445;7059;7984.361251100015;8185.959070204611;8275.213854683796;8304.958475207586;8313.709020184264;8199.467034642728;8243.0520490142;8258.710945116893;8375.623096960773;8384.413091083565;7036.085308909809;7118.572885626879;7128.287171283037;7130.572165670076;7135.143782746104;7513;7568.659410326439;7589.241075892367;7616.961915190389;7619.504732192389;7192;7212.927870721435;7214.89177662346;7281.2718728126765;7307.5980821055355;6901.206361268519;7064.116985376828;7077.115360579927;7165.0327205828635;7213.567185968858;7176.067111275501;7213.531120477152;7218.963905180441;7220.638968051471;7225.421966242793;6460.773872914426;6537.084808126771;6553.442957348679;6591;6616.636085015371;6526.24948130952;6563.606183628997;6566.409023187936;6569.408753212234;6569.638669873012 21:42:07 INFO - 2015-11-20 21:42:07,060 DEBUG : BROWSER_OUTPUT: |4;prototype.html;348.43553443421655;352.5980382364104;353.5139183622519;354.9325628130656;358.6162805797796;6048.092786082085;6101.328853826087;6106.053561697939;6131.938680613192;6144.416280453361;5748.223989761381;5822.767089316422;5921.85195370116;5982.132590774346;6006;4660.440747110351;4661.300804879262;4663;4676.7427791471455;4696.577308042276;4445.088756804847;4678.485366609671;4681.274402467623;4684.414448193976;4689.20283551797;4531.614812740921;4533.410656614636;4644.72131672099;4649.163150632885;4665.206914824471;4619.792109355087;4629.675922685414;4634.559716826913;4637.3275874997935;4649.488556258809;4091.447654566655;4095.795210239506;4101.753894766323;4111.568285330027;4117.135401565675;5576.9163462548095;5659.773609055633;5668.716564171775;5671;5676.489115979541;5329.493698098674;5332.786688532454;5335.546478549328;5343.919841202385;5350.678959262418;6418.19772528434;6430.903536446957;6435.163428754278;6438.420542151229;6444.806655800334;4450.799714012877;4452.0205554777895;4453.665975051852;4459.821607135711;4469.620082293009;6505.674716264215;6506.284308726036;6628.73485060597;6637.311888685091;6645.734170633169;4554.225781617133;4554.7039442436135;4554.908901821945;4561.7262964222255;4568.383268258809;4607.424071991001;4609.8156073757345;4611.723296602215;4616.699914505546;4617.007343421114;5305.575553955751;5318.282031925641;5325.227841962894;5325.227841962971;5329.600279979017;6270.843228919313;6459.580127291711;6463.06285588587;6468.450181734605;6473.06140609609;1619.627485678277;1640.516047765912;1641.4090927266175;1642.0229963171894;1642.4005238088012;1076.9138468922467;1083.772407794372;1084.3331351218944;1088.3361149698678;1096.7313008313015;6256.124142620037;6271.749130034747;6275.654838983808;6283.340249273851;6283.5287353448675;6394.392532709385;6636.2368327642935;6646.036324732887;6651.201855777241;6662;6126.448619624255;6213.285472170758;6279.058141278753;6342.714577844008;6351.809445716636;3570.8928732137556;3594.928101438009;3616.9819150904077;3619.3666108431444;3619.7828130312264;6754.696038678271;6987.231404545496;6989.161300643956;6992.580445173306;6995.440364770806;6875.5530890492955;6882.552634078769;6894.482913781487;6898.793036208922;6901.551399159139;6992.825179370557;6999;7000.229974702778;7001.614911179934;7002.124734408199;6560.704768285344;6698.095757072928;6698.363655452724;6700.329967003261;6701.765438209639;6644.368784965421;6648.867022659423;6657.40083392497;6658.400743933069;6660.83347916306;6972.1284839395075;6977.755778547727;6981.232064472802;6994.440444764305;7001.754938577125;4454.710443821141;4504.414426124583;4504.954950450454;4505.279155335131;4509.098180363875;6694.59832410057;6703.530752847254;6707.698153583003;6711.664416779044;6715.865682686222;6473.514486413444;6496.285408605144;6497.447716943923;6503.382178693017;6518.674066296761;6380.266269379081;6485.805425837232;6490.967545162244;6493.74025039012;6501.707423165874;6376.234851817626;6386.361363863576;6387.808365749035;6391.648459334595;6403.743850245938;6495.480361571157;6508.446782023581;6509.8698026040165;6511.218653761579;6524.771632992823;6215.098810672337;6381.3937675920715;6383.585066970632;6383.744650213939;6400 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: |5;yui.html;847.51268020888;851.569957171629;856.1737922904398;858.6393714639847;866.0603245478655;12948.287844168566;13818.723625527497;13949.302534873266;13973.860261397396;14007.579772606812;12809.871901280985;12900.09699321048;12901.1614245074;12905.22568645882;12921.289329086896;672.6434989455596;677.7289084366244;824.46821799939;838.2581415447312;838.5010918503481;834.9958250208771;838.8909441772591;847.1655419411875;849.235687880909;849.6304107713164;846.2637505370336;849.5285116760195;850.3324889961382;854.8204876975842;856.1267507142711;705.9576425414466;710.9111361079865;712.0245263988343;716.2550947015098;838.4801423117675;627;628.8176428835632;630.1461519640867;631.4790298004165;634.4416913116428;12095.153339266255;12360.381980900918;12364.690882727913;12369.257844529267;12380;11657.650270491898;12150;12194.207376520499;12355.073369497324;12362.75274494505;10509.369437833755;10711.732206694816;10712.78574428515;10790.244682872202;10805.945970270177;3633.6003039665616;3682.2819550187724;3711.6288371162937;3803.4675145479655;3807.276617442677;44813.327800083025;45097.77451112724;45101.54898451006;45204;45255.547444525466;9971.551280192263;10015.599376025024;10125.949370253249;10202.846957295647;10418.06237438619;17680.381186658404;18225.362112326005;18231.817681823013;18259.178336974866;18261.63476730458;7722.652480638385;7752.108507521707;7776.1057478389575;7777.92222077772;7951.721689740832;4414.381986521889;4450.732956022649;4466;4581.94615238497;4642.326862604903;2968.732814046746;2985.149232468736;2985.268609190762;2989;2993.670696223414;565.3470241870646;579.7043507811047;582.4786815799836;586.0857062981762;587.0753563138074;512.4772731813604;542.1704791668753;599.2120361724319;630.5523078614143;643.0386572074684;8038.557879316693;8279;8480.321574274043;8570.185832346042;8590.269827064769;466.4123204762024;563.9436056394328;573.724612186153;587.1369087441454;652.0870780906769;3546.4325707886615;3603.6756691897854;3633.9818300908855;3642.8907132786057;3860.4595356650093;56273.7186314074;56979.715101424226;57059;57325.14012289819;57565.42434575601;50029.49970500321;50148.24777628339;50526.24210636843;50871;51333.23000155001;45229.54770452319;45370.31944520835;46051.53948460473;46066.76966615146;46622.5337746625;7834.138244793182;8204.712835050745;8366.079731229682;8623.267022302924;8901.955490222508;33376.66623333735;33968;34097;34366;35686.28627427489;43189.7840510808;43980.34029489624;44599.55400445954;44769.77615111903;45037.32444013277;10483.895161048596;10603.734906627396;10684.786304274026;10699.518521666389;11109.11127109813;11973.16187866841;11998.446803002791;12079.57721479744;12109.33398663082;12802.67993300175;10831.729206769895;11111.611093611686;11182.888171118184;11323.32060076398;11378.203525753448;10607.257491975488;10790.352578845295;11293.153013523854;11302.152338574475;11786.528538858349;10949.278203174772;10960.835587466036;11050.723731906766;11282.887171128184;11303.095752339957;9872.802543948937;10238.18094552449;10251.487425628839;10304.330218535772;10662.786744264917;8302.684673685;8711;8795.868061979203;8969.686060987835;9464.148226659634 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: __start_cc_report 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1734 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: __end_cc_report 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: __startTimestamp1448084527050__endTimestamp 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: Number of tests: 6 21:42:07 INFO - 2015-11-20 21:42:07,062 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,063 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5517.16 Median:5692.46 stddev:613.80 (10.8%) stddev-sans-first:588.63 21:42:07 INFO - 2015-11-20 21:42:07,063 DEBUG : BROWSER_OUTPUT: Values: 3219.9 3324.7 3353.0 3375.6 3379.4 5353.9 5486.7 5494.9 5539.0 5570.2 5390.3 5462.4 5501.1 5512.2 5549.4 4969.7 4993.2 5021.3 5043.7 5099.6 4871.1 5086.8 5091.2 5102.9 5196.5 4985.9 4993.4 5037.4 5058.4 5059.7 5051.5 5066.6 5068.4 5102.6 5127.8 4852.4 4950.7 5032.9 5043.6 5046.6 5243.8 5304.5 5326.0 5351.0 5356.8 5993.8 5994.9 5994.9 6000.9 6004.5 5752.1 5807.3 5861.6 6024.6 6028.5 5506.6 5592.8 5599.2 5603.5 5638.1 6075.8 6085.5 6097.7 6105.3 6107.3 5773.5 5823.2 5894.6 5951.9 5977.9 5817.2 5860.3 5914.4 5915.8 5966.5 5948.4 6056.7 6072.5 6073.8 6079.7 5824.9 5935.9 5949.6 6050.8 6077.0 4522.7 4549.7 4572.6 4586.4 4617.0 4095.9 4110.0 4117.4 4138.5 4142.1 5526.6 5641.1 5666.2 5674.5 5677.2 5901.0 5959.6 5968.0 5976.5 6022.2 5602.0 5634.2 5642.2 5647.5 5654.9 5036.3 5069.9 5195.6 5199.1 5200.2 6094.5 6155.8 6160.1 6165.6 6175.0 5953.2 5981.5 5981.7 5983.9 6027.1 5965.6 6082.6 6084.8 6225.9 6233.4 5772.6 5871.0 5891.8 5904.8 5910.9 5940.7 5975.0 5978.5 5991.2 6007.6 5666.5 5791.6 5835.9 5858.8 5860.9 5755.0 5765.3 5770.6 5773.0 5773.1 5683.4 5685.8 5691.9 5692.5 5697.2 5702.1 5767.5 5801.4 5807.9 5808.0 5645.0 5660.8 5688.0 5697.8 5701.9 21:42:07 INFO - 2015-11-20 21:42:07,063 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51400.76 Median:35775.87 stddev:93678.99 (261.8%) stddev-sans-first:93863.94 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: Values: 536.8 596.8 598.7 610.6 618.1 37081.8 37506.0 37548.6 37579.6 37583.2 41604.0 41707.8 42041.8 42050.0 42070.6 7722.2 7831.8 7847.5 8727.7 8905.2 10486.8 10718.7 10722.7 10741.9 10743.2 10370.2 10458.3 10474.9 10477.3 10494.3 7961.0 8127.6 8136.1 10252.9 10419.5 10311.4 10364.7 10462.3 10465.6 10471.8 39719.8 40290.0 40318.4 40352.6 40355.4 3483.6 3514.8 3542.0 3857.7 3936.1 30396.5 30554.8 30563.2 30592.0 30962.5 3910.0 3989.7 4024.2 4078.5 4186.8 273787.0 276399.0 276899.0 276945.0 277978.6 55281.4 55818.2 60092.7 63618.7 63921.7 56064.0 56399.0 56519.7 56752.0 57056.0 15849.7 15932.6 15995.4 16014.3 16016.8 11998.6 12132.2 12162.9 12186.8 12186.9 20331.4 20639.3 20651.4 20669.7 20704.7 7912.6 8045.2 8063.0 8100.5 8111.7 927.2 937.1 1004.6 1095.3 1149.9 28333.6 28694.4 28837.1 29014.4 29017.7 1062.4 1068.8 1083.1 1093.6 1167.8 14363.5 14404.6 14605.0 14924.6 16423.5 411852.0 415115.0 415264.0 416629.0 418435.0 53091.0 53187.0 53518.0 53587.7 53697.5 1354.7 1354.9 1394.0 1411.9 1418.7 29874.4 30454.1 30590.2 30742.8 30749.4 43014.8 45661.0 46193.0 46204.5 46299.3 205930.0 384709.0 391962.0 392042.0 393350.0 32028.0 32682.0 32831.8 32899.8 32949.0 42385.8 42585.6 42892.6 43005.0 43751.8 35589.5 35962.3 36380.3 36455.3 37087.0 36635.0 36734.4 37004.8 37333.4 37368.6 34514.8 40673.0 40904.0 41621.6 41655.6 30939.1 31071.7 33733.3 33842.2 33938.0 25801.6 26232.7 26573.7 27109.6 27371.5 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29759.11 Median:18529.38 stddev:92074.84 (496.9%) stddev-sans-first:92306.67 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: Values: 358.2 363.6 364.4 364.9 368.7 14175.6 14284.9 16817.0 17294.8 17881.3 14856.4 14959.8 15028.0 16025.8 17876.7 5328.8 5500.6 5501.2 5502.3 5506.3 4117.2 4163.1 5428.8 5731.6 5922.1 5814.5 5848.9 5850.2 5869.3 5885.6 5761.5 5929.6 5930.5 5935.8 5937.3 5202.0 5242.6 5254.9 5264.0 5268.6 19773.7 19848.1 20187.6 20273.3 20274.4 2063.9 2082.6 2128.8 2136.2 2163.6 8907.6 8970.7 9650.0 9741.4 10719.8 1764.5 1827.8 1828.4 1831.7 1901.9 62801.0 62981.0 63166.0 66144.7 66328.7 10463.7 10466.1 10474.5 10511.3 10620.3 13623.3 13643.8 13664.1 13751.9 13758.2 7915.1 7924.5 8000.6 8068.8 8078.8 7772.2 7775.3 7787.7 7827.3 7827.5 3988.7 4182.6 4416.3 4532.2 4549.3 1725.7 1761.7 1780.6 1814.8 1849.6 890.3 921.4 956.6 991.6 1027.6 14426.7 16347.0 16562.7 16934.2 17874.9 884.9 914.4 916.1 924.3 975.4 9740.4 9958.5 10026.2 10036.3 10048.7 554065.0 554413.0 560145.0 573072.0 575011.0 78048.0 78721.0 80999.0 82160.6 83038.0 1173.0 1175.4 1186.9 1190.3 1194.4 15498.4 15544.1 15758.9 15761.5 15775.8 36486.6 36690.1 37259.6 37575.4 37611.4 47564.5 47962.5 49024.5 50832.7 52403.0 9161.0 9389.5 9627.0 9635.7 9683.9 11469.2 11549.4 11613.9 12925.8 17679.4 14229.7 14257.6 14422.1 14647.9 14662.6 14496.4 14679.9 14781.7 14846.5 15251.0 15212.8 15351.5 15422.4 15717.0 15816.5 9541.6 9576.2 9682.7 9729.0 9742.8 14619.4 14782.2 14820.8 14972.0 15171.8 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5391.03 Median:6558.52 stddev:2770.35 (42.2%) stddev-sans-first:2762.98 21:42:07 INFO - 2015-11-20 21:42:07,064 DEBUG : BROWSER_OUTPUT: Values: 1538.2 1689.1 1742.7 1757.5 1780.1 6705.8 7488.6 7649.3 7664.2 7673.8 7552.1 7664.0 7674.3 7676.6 7679.0 2296.6 2323.1 2324.1 2324.7 2324.9 2324.5 2345.2 2359.9 2363.7 2365.7 2262.3 2295.2 2297.5 2298.6 2308.2 2284.5 2286.0 2303.4 2307.0 2315.8 2336.2 2337.1 2350.8 2355.4 2356.2 6573.4 6687.3 7562.2 7571.6 7603.7 4220.8 4264.9 4289.3 4428.4 4480.0 2966.4 3028.6 3068.8 3080.1 3112.9 976.9 980.2 998.2 1012.5 1045.7 10540.9 10587.2 10610.2 10616.6 10626.9 4244.4 4259.5 4286.1 4296.6 4367.7 9107.9 9159.3 9207.2 9215.5 9264.9 4731.7 4771.1 4772.0 4773.2 4784.6 4307.2 4322.5 4332.6 4356.9 4358.3 3602.3 3651.9 3662.4 3670.3 3674.2 1143.9 1150.6 1151.7 1153.4 1157.5 1037.6 1062.6 1062.9 1230.2 1383.7 7336.1 7347.6 7377.3 7426.4 7431.1 461.9 464.5 465.3 471.2 473.0 5397.4 5753.0 5847.2 5856.1 5858.6 8483.8 8495.8 8609.0 8650.8 8736.5 8594.8 8602.1 8722.4 8758.0 8889.7 7128.4 7151.2 7155.6 7182.0 7229.2 6970.2 7045.7 7051.1 7053.2 7059.0 7984.4 8186.0 8275.2 8305.0 8313.7 8199.5 8243.1 8258.7 8375.6 8384.4 7036.1 7118.6 7128.3 7130.6 7135.1 7513.0 7568.7 7589.2 7617.0 7619.5 7192.0 7212.9 7214.9 7281.3 7307.6 6901.2 7064.1 7077.1 7165.0 7213.6 7176.1 7213.5 7219.0 7220.6 7225.4 6460.8 6537.1 6553.4 6591.0 6616.6 6526.2 6563.6 6566.4 6569.4 6569.6 21:42:07 INFO - 2015-11-20 21:42:07,065 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,065 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5400.54 Median:6178.85 stddev:1636.80 (26.5%) stddev-sans-first:1596.87 21:42:07 INFO - 2015-11-20 21:42:07,066 DEBUG : BROWSER_OUTPUT: Values: 348.4 352.6 353.5 354.9 358.6 6048.1 6101.3 6106.1 6131.9 6144.4 5748.2 5822.8 5921.9 5982.1 6006.0 4660.4 4661.3 4663.0 4676.7 4696.6 4445.1 4678.5 4681.3 4684.4 4689.2 4531.6 4533.4 4644.7 4649.2 4665.2 4619.8 4629.7 4634.6 4637.3 4649.5 4091.4 4095.8 4101.8 4111.6 4117.1 5576.9 5659.8 5668.7 5671.0 5676.5 5329.5 5332.8 5335.5 5343.9 5350.7 6418.2 6430.9 6435.2 6438.4 6444.8 4450.8 4452.0 4453.7 4459.8 4469.6 6505.7 6506.3 6628.7 6637.3 6645.7 4554.2 4554.7 4554.9 4561.7 4568.4 4607.4 4609.8 4611.7 4616.7 4617.0 5305.6 5318.3 5325.2 5325.2 5329.6 6270.8 6459.6 6463.1 6468.5 6473.1 1619.6 1640.5 1641.4 1642.0 1642.4 1076.9 1083.8 1084.3 1088.3 1096.7 6256.1 6271.7 6275.7 6283.3 6283.5 6394.4 6636.2 6646.0 6651.2 6662.0 6126.4 6213.3 6279.1 6342.7 6351.8 3570.9 3594.9 3617.0 3619.4 3619.8 6754.7 6987.2 6989.2 6992.6 6995.4 6875.6 6882.6 6894.5 6898.8 6901.6 6992.8 6999.0 7000.2 7001.6 7002.1 6560.7 6698.1 6698.4 6700.3 6701.8 6644.4 6648.9 6657.4 6658.4 6660.8 6972.1 6977.8 6981.2 6994.4 7001.8 4454.7 4504.4 4505.0 4505.3 4509.1 6694.6 6703.5 6707.7 6711.7 6715.9 6473.5 6496.3 6497.4 6503.4 6518.7 6380.3 6485.8 6491.0 6493.7 6501.7 6376.2 6386.4 6387.8 6391.6 6403.7 6495.5 6508.4 6509.9 6511.2 6524.8 6215.1 6381.4 6383.6 6383.7 6400.0 21:42:07 INFO - 2015-11-20 21:42:07,066 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,066 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13572.23 Median:44925.33 stddev:15628.40 (34.8%) stddev-sans-first:15643.03 21:42:07 INFO - 2015-11-20 21:42:07,066 DEBUG : BROWSER_OUTPUT: Values: 847.5 851.6 856.2 858.6 866.1 12948.3 13818.7 13949.3 13973.9 14007.6 12809.9 12900.1 12901.2 12905.2 12921.3 672.6 677.7 824.5 838.3 838.5 835.0 838.9 847.2 849.2 849.6 846.3 849.5 850.3 854.8 856.1 706.0 710.9 712.0 716.3 838.5 627.0 628.8 630.1 631.5 634.4 12095.2 12360.4 12364.7 12369.3 12380.0 11657.7 12150.0 12194.2 12355.1 12362.8 10509.4 10711.7 10712.8 10790.2 10805.9 3633.6 3682.3 3711.6 3803.5 3807.3 44813.3 45097.8 45101.5 45204.0 45255.5 9971.6 10015.6 10125.9 10202.8 10418.1 17680.4 18225.4 18231.8 18259.2 18261.6 7722.7 7752.1 7776.1 7777.9 7951.7 4414.4 4450.7 4466.0 4581.9 4642.3 2968.7 2985.1 2985.3 2989.0 2993.7 565.3 579.7 582.5 586.1 587.1 512.5 542.2 599.2 630.6 643.0 8038.6 8279.0 8480.3 8570.2 8590.3 466.4 563.9 573.7 587.1 652.1 3546.4 3603.7 3634.0 3642.9 3860.5 56273.7 56979.7 57059.0 57325.1 57565.4 50029.5 50148.2 50526.2 50871.0 51333.2 45229.5 45370.3 46051.5 46066.8 46622.5 7834.1 8204.7 8366.1 8623.3 8902.0 33376.7 33968.0 34097.0 34366.0 35686.3 43189.8 43980.3 44599.6 44769.8 45037.3 10483.9 10603.7 10684.8 10699.5 11109.1 11973.2 11998.4 12079.6 12109.3 12802.7 10831.7 11111.6 11182.9 11323.3 11378.2 10607.3 10790.4 11293.2 11302.2 11786.5 10949.3 10960.8 11050.7 11282.9 11303.1 9872.8 10238.2 10251.5 10304.3 10662.8 8302.7 8711.0 8795.9 8969.7 9464.1 21:42:07 INFO - 2015-11-20 21:42:07,066 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:42:07 INFO - 2015-11-20 21:42:07,066 DEBUG : BROWSER_OUTPUT: 21:42:07 INFO - 2015-11-20 21:42:07,786 INFO : Browser exited with error code: 0 21:42:07 INFO - 2015-11-20 21:42:07,805 INFO : Completed test dromaeo_css (00:18:34) 21:42:07 INFO - 2015-11-20 21:42:07,805 INFO : Starting test kraken 21:42:07 INFO - 2015-11-20 21:42:07,805 DEBUG : operating with platform_type : linux_ 21:42:07 INFO - 2015-11-20 21:42:07,805 INFO : Initialising browser for kraken test... 21:42:07 INFO - 2015-11-20 21:42:07,811 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgLRCG0/profile http://localhost:42290/getInfo.html 21:42:11 INFO - 2015-11-20 21:42:11,696 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:42:11 INFO - 2015-11-20 21:42:11,696 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:42:11 INFO - 2015-11-20 21:42:11,696 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:42:11 INFO - 2015-11-20 21:42:11,696 DEBUG : BROWSER_OUTPUT: __metrics 21:42:11 INFO - 2015-11-20 21:42:11,833 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:42:13 INFO - 2015-11-20 21:42:13,080 INFO : Browser initialized. 21:42:13 INFO - 2015-11-20 21:42:13,081 INFO : Running cycle 1/1 for kraken test... 21:42:13 INFO - 2015-11-20 21:42:13,081 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgLRCG0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:42:24 INFO - 2015-11-20 21:42:24,884 DEBUG : BROWSER_OUTPUT: RSS: Main: 140402688 21:42:24 INFO - 2015-11-20 21:42:24,885 DEBUG : BROWSER_OUTPUT: 21:42:27 INFO - 2015-11-20 21:42:27,359 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-beat-detection) 21:42:27 INFO - 2015-11-20 21:42:27,940 DEBUG : BROWSER_OUTPUT: RSS: Main: 178659328 21:42:27 INFO - 2015-11-20 21:42:27,941 DEBUG : BROWSER_OUTPUT: 21:42:30 INFO - 2015-11-20 21:42:30,496 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-dft) 21:42:31 INFO - 2015-11-20 21:42:31,062 DEBUG : BROWSER_OUTPUT: RSS: Main: 167051264 21:42:31 INFO - 2015-11-20 21:42:31,062 DEBUG : BROWSER_OUTPUT: 21:42:33 INFO - 2015-11-20 21:42:33,945 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-fft) 21:42:34 INFO - 2015-11-20 21:42:34,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 152317952 21:42:34 INFO - 2015-11-20 21:42:34,507 DEBUG : BROWSER_OUTPUT: 21:42:36 INFO - 2015-11-20 21:42:36,470 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-oscillator) 21:42:37 INFO - 2015-11-20 21:42:37,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 155357184 21:42:37 INFO - 2015-11-20 21:42:37,036 DEBUG : BROWSER_OUTPUT: 21:42:39 INFO - 2015-11-20 21:42:39,190 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42290/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:42:39 INFO - 2015-11-20 21:42:39,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 155459584 21:42:39 INFO - 2015-11-20 21:42:39,765 DEBUG : BROWSER_OUTPUT: 21:42:42 INFO - 2015-11-20 21:42:42,635 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42290/tests/kraken/driver.html?testName=imaging-darkroom) 21:42:43 INFO - 2015-11-20 21:42:43,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 154456064 21:42:43 INFO - 2015-11-20 21:42:43,204 DEBUG : BROWSER_OUTPUT: 21:42:46 INFO - 2015-11-20 21:42:46,652 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42290/tests/kraken/driver.html?testName=imaging-desaturate) 21:42:47 INFO - 2015-11-20 21:42:47,225 DEBUG : BROWSER_OUTPUT: RSS: Main: 157020160 21:42:47 INFO - 2015-11-20 21:42:47,226 DEBUG : BROWSER_OUTPUT: 21:42:49 INFO - 2015-11-20 21:42:49,968 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42290/tests/kraken/driver.html?testName=json-parse-financial) 21:42:50 INFO - 2015-11-20 21:42:50,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 156762112 21:42:50 INFO - 2015-11-20 21:42:50,541 DEBUG : BROWSER_OUTPUT: 21:42:52 INFO - 2015-11-20 21:42:52,097 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42290/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:42:52 INFO - 2015-11-20 21:42:52,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 167325696 21:42:52 INFO - 2015-11-20 21:42:52,664 DEBUG : BROWSER_OUTPUT: 21:42:54 INFO - 2015-11-20 21:42:54,475 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:42:55 INFO - 2015-11-20 21:42:55,081 DEBUG : BROWSER_OUTPUT: RSS: Main: 165777408 21:42:55 INFO - 2015-11-20 21:42:55,081 DEBUG : BROWSER_OUTPUT: 21:42:57 INFO - 2015-11-20 21:42:57,140 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:42:57 INFO - 2015-11-20 21:42:57,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 170582016 21:42:57 INFO - 2015-11-20 21:42:57,714 DEBUG : BROWSER_OUTPUT: 21:43:00 INFO - 2015-11-20 21:43:00,128 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:43:00 INFO - 2015-11-20 21:43:00,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 179458048 21:43:00 INFO - 2015-11-20 21:43:00,709 DEBUG : BROWSER_OUTPUT: 21:43:03 INFO - 2015-11-20 21:43:03,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:43:03 INFO - 2015-11-20 21:43:03,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 178724864 21:43:03 INFO - 2015-11-20 21:43:03,875 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,565 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42290/tests/kraken/driver.html?testName=ai-astar) 21:43:05 INFO - 2015-11-20 21:43:05,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 238387200 21:43:05 INFO - 2015-11-20 21:43:05,815 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,817 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:43:05 INFO - 2015-11-20 21:43:05,817 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:43:05 INFO - 2015-11-20 21:43:05,817 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:43:05 INFO - 2015-11-20 21:43:05,817 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:43:05 INFO - 2015-11-20 21:43:05,818 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;106;111;112;110;99;109;115;109 21:43:05 INFO - 2015-11-20 21:43:05,818 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;124;149;160;126;155;146;126;169;125 21:43:05 INFO - 2015-11-20 21:43:05,818 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;162;162;161;162;162;163;161;163 21:43:05 INFO - 2015-11-20 21:43:05,818 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;82;92;72;72;72;98;91;72 21:43:05 INFO - 2015-11-20 21:43:05,819 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;90;135;90;90;90;144;90;91;130 21:43:05 INFO - 2015-11-20 21:43:05,819 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;109;110;109;109;109;109 21:43:05 INFO - 2015-11-20 21:43:05,819 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;145;146;145;147;145;146;146 21:43:05 INFO - 2015-11-20 21:43:05,820 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;96;96;97;96;96;96;96 21:43:05 INFO - 2015-11-20 21:43:05,820 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;61;58;56;57;57;57;65;54;55 21:43:05 INFO - 2015-11-20 21:43:05,820 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;70;69;69;69;68;69;71;69;69 21:43:05 INFO - 2015-11-20 21:43:05,821 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;85;86;86;85;87;87;86;95;86 21:43:05 INFO - 2015-11-20 21:43:05,822 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;131;122;116;117;115;127;115;117;125;115 21:43:05 INFO - 2015-11-20 21:43:05,822 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;143;145;156;158;150;141;161;152;154;142 21:43:05 INFO - 2015-11-20 21:43:05,822 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;69;66;66;67;68;66;68;67;67 21:43:05 INFO - 2015-11-20 21:43:05,822 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:43:05 INFO - 2015-11-20 21:43:05,823 DEBUG : BROWSER_OUTPUT: __start_cc_report 21:43:05 INFO - 2015-11-20 21:43:05,823 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,936 21:43:05 INFO - 2015-11-20 21:43:05,823 DEBUG : BROWSER_OUTPUT: __end_cc_report 21:43:05 INFO - 2015-11-20 21:43:05,823 DEBUG : BROWSER_OUTPUT: __startTimestamp1448084585816__endTimestamp 21:43:05 INFO - 2015-11-20 21:43:05,824 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,825 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:43:05 INFO - 2015-11-20 21:43:05,825 DEBUG : BROWSER_OUTPUT: Number of tests: 14 21:43:05 INFO - 2015-11-20 21:43:05,826 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,826 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.10 Median:110.50 stddev:4.23 (3.8%) stddev-sans-first:4.47 21:43:05 INFO - 2015-11-20 21:43:05,826 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 106.0 111.0 112.0 110.0 99.0 109.0 115.0 109.0 21:43:05 INFO - 2015-11-20 21:43:05,826 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,826 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.00 Median:149.50 stddev:16.55 (11.1%) stddev-sans-first:17.36 21:43:05 INFO - 2015-11-20 21:43:05,826 DEBUG : BROWSER_OUTPUT: Values: 150.0 124.0 149.0 160.0 126.0 155.0 146.0 126.0 169.0 125.0 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 162.0 162.0 161.0 162.0 162.0 163.0 161.0 163.0 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.40 Median:77.00 stddev:10.49 (13.6%) stddev-sans-first:10.78 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 82.0 92.0 72.0 72.0 72.0 98.0 91.0 72.0 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,827 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.10 Median:90.00 stddev:22.50 (25.0%) stddev-sans-first:23.36 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: Values: 91.0 90.0 135.0 90.0 90.0 90.0 144.0 90.0 91.0 130.0 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 145.0 146.0 145.0 147.0 145.0 146.0 146.0 21:43:05 INFO - 2015-11-20 21:43:05,828 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,829 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 21:43:05 INFO - 2015-11-20 21:43:05,829 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 21:43:05 INFO - 2015-11-20 21:43:05,829 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,829 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.90 Median:57.50 stddev:3.18 (5.5%) stddev-sans-first:3.35 21:43:05 INFO - 2015-11-20 21:43:05,829 DEBUG : BROWSER_OUTPUT: Values: 59.0 61.0 58.0 56.0 57.0 57.0 57.0 65.0 54.0 55.0 21:43:05 INFO - 2015-11-20 21:43:05,829 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,829 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:0.88 (1.3%) stddev-sans-first:0.83 21:43:05 INFO - 2015-11-20 21:43:05,830 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 69.0 69.0 69.0 68.0 69.0 71.0 69.0 69.0 21:43:05 INFO - 2015-11-20 21:43:05,830 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,830 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:86.00 stddev:3.06 (3.6%) stddev-sans-first:3.08 21:43:05 INFO - 2015-11-20 21:43:05,830 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 86.0 86.0 85.0 87.0 87.0 86.0 95.0 86.0 21:43:05 INFO - 2015-11-20 21:43:05,830 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,830 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.00 Median:119.50 stddev:5.85 (4.9%) stddev-sans-first:4.66 21:43:05 INFO - 2015-11-20 21:43:05,830 DEBUG : BROWSER_OUTPUT: Values: 131.0 122.0 116.0 117.0 115.0 127.0 115.0 117.0 125.0 115.0 21:43:05 INFO - 2015-11-20 21:43:05,831 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,831 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.20 Median:153.00 stddev:7.15 (4.7%) stddev-sans-first:7.09 21:43:05 INFO - 2015-11-20 21:43:05,831 DEBUG : BROWSER_OUTPUT: Values: 143.0 145.0 156.0 158.0 150.0 141.0 161.0 152.0 154.0 142.0 21:43:05 INFO - 2015-11-20 21:43:05,831 DEBUG : BROWSER_OUTPUT: 21:43:05 INFO - 2015-11-20 21:43:05,831 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 21:43:05 INFO - 2015-11-20 21:43:05,832 DEBUG : BROWSER_OUTPUT: Values: 67.0 69.0 66.0 66.0 67.0 68.0 66.0 68.0 67.0 67.0 21:43:05 INFO - 2015-11-20 21:43:05,833 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:43:05 INFO - 2015-11-20 21:43:05,833 DEBUG : BROWSER_OUTPUT: 21:43:06 INFO - 2015-11-20 21:43:06,702 INFO : Browser exited with error code: 0 21:43:06 INFO - 2015-11-20 21:43:06,754 INFO : Completed test kraken (00:00:58) 21:43:06 INFO - 2015-11-20 21:43:06,754 INFO : Starting test v8_7 21:43:06 INFO - 2015-11-20 21:43:06,754 DEBUG : operating with platform_type : linux_ 21:43:06 INFO - 2015-11-20 21:43:06,754 INFO : Initialising browser for v8_7 test... 21:43:06 INFO - 2015-11-20 21:43:06,760 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvx0BnZ/profile http://localhost:42290/getInfo.html 21:43:10 INFO - 2015-11-20 21:43:10,730 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:43:10 INFO - 2015-11-20 21:43:10,730 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:43:10 INFO - 2015-11-20 21:43:10,730 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:43:10 INFO - 2015-11-20 21:43:10,730 DEBUG : BROWSER_OUTPUT: __metrics 21:43:10 INFO - 2015-11-20 21:43:10,818 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:43:12 INFO - 2015-11-20 21:43:12,180 INFO : Browser initialized. 21:43:12 INFO - 2015-11-20 21:43:12,180 INFO : Running cycle 1/1 for v8_7 test... 21:43:12 INFO - 2015-11-20 21:43:12,181 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvx0BnZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:43:23 INFO - 2015-11-20 21:43:23,948 DEBUG : BROWSER_OUTPUT: RSS: Main: 142127104 21:43:23 INFO - 2015-11-20 21:43:23,949 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,382 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42290/tests/v8_7/run.html (next: http://localhost:42290/tests/v8_7/run.html) 21:43:45 INFO - 2015-11-20 21:43:45,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 611323904 21:43:45 INFO - 2015-11-20 21:43:45,632 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,633 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:43:45 INFO - 2015-11-20 21:43:45,633 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:43:45 INFO - 2015-11-20 21:43:45,633 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:43:45 INFO - 2015-11-20 21:43:45,634 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:43:45 INFO - 2015-11-20 21:43:45,634 DEBUG : BROWSER_OUTPUT: |0;Richards;162.86644951140065 21:43:45 INFO - 2015-11-20 21:43:45,634 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.07598269700958 21:43:45 INFO - 2015-11-20 21:43:45,634 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.214848143982 21:43:45 INFO - 2015-11-20 21:43:45,634 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5025.125628140703 21:43:45 INFO - 2015-11-20 21:43:45,634 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1107.4197120708748 21:43:45 INFO - 2015-11-20 21:43:45,634 DEBUG : BROWSER_OUTPUT: |5;Earley;783.6990595611285 21:43:45 INFO - 2015-11-20 21:43:45,635 DEBUG : BROWSER_OUTPUT: |6;Boyer;12207.317073170732 21:43:45 INFO - 2015-11-20 21:43:45,635 DEBUG : BROWSER_OUTPUT: |7;RegExp;25575 21:43:45 INFO - 2015-11-20 21:43:45,635 DEBUG : BROWSER_OUTPUT: |8;Splay;645.1612903225806 21:43:45 INFO - 2015-11-20 21:43:45,635 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 21:43:45 INFO - 2015-11-20 21:43:45,635 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:43:45 INFO - 2015-11-20 21:43:45,635 DEBUG : BROWSER_OUTPUT: __startTimestamp1448084625632__endTimestamp 21:43:45 INFO - 2015-11-20 21:43:45,636 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,636 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:43:45 INFO - 2015-11-20 21:43:45,636 DEBUG : BROWSER_OUTPUT: Number of tests: 10 21:43:45 INFO - 2015-11-20 21:43:45,636 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,636 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.87 Median:162.87 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,637 DEBUG : BROWSER_OUTPUT: Values: 162.9 21:43:45 INFO - 2015-11-20 21:43:45,638 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,638 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.08 Median:188.08 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,638 DEBUG : BROWSER_OUTPUT: Values: 188.1 21:43:45 INFO - 2015-11-20 21:43:45,639 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,639 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.21 Median:281.21 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,639 DEBUG : BROWSER_OUTPUT: Values: 281.2 21:43:45 INFO - 2015-11-20 21:43:45,639 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,639 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5025.13 Median:5025.13 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,639 DEBUG : BROWSER_OUTPUT: Values: 5025.1 21:43:45 INFO - 2015-11-20 21:43:45,640 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,640 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1107.42 Median:1107.42 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,640 DEBUG : BROWSER_OUTPUT: Values: 1107.4 21:43:45 INFO - 2015-11-20 21:43:45,640 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,640 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:783.70 Median:783.70 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,640 DEBUG : BROWSER_OUTPUT: Values: 783.7 21:43:45 INFO - 2015-11-20 21:43:45,640 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,641 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,641 DEBUG : BROWSER_OUTPUT: Values: 12207.3 21:43:45 INFO - 2015-11-20 21:43:45,641 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,641 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25575.00 Median:25575.00 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,641 DEBUG : BROWSER_OUTPUT: Values: 25575.0 21:43:45 INFO - 2015-11-20 21:43:45,641 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,642 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:645.16 Median:645.16 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,642 DEBUG : BROWSER_OUTPUT: Values: 645.2 21:43:45 INFO - 2015-11-20 21:43:45,642 DEBUG : BROWSER_OUTPUT: 21:43:45 INFO - 2015-11-20 21:43:45,642 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 21:43:45 INFO - 2015-11-20 21:43:45,642 DEBUG : BROWSER_OUTPUT: Values: 4830.9 21:43:45 INFO - 2015-11-20 21:43:45,642 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:43:45 INFO - 2015-11-20 21:43:45,642 DEBUG : BROWSER_OUTPUT: 21:43:46 INFO - 2015-11-20 21:43:46,502 INFO : Browser exited with error code: 0 21:43:46 INFO - 2015-11-20 21:43:46,519 INFO : Completed test v8_7 (00:00:39) 21:43:46 INFO - 2015-11-20 21:43:46,608 INFO : Completed test suite (00:20:12) 21:43:46 INFO - 2015-11-20 21:43:46,609 DEBUG : Working with test: dromaeo_css 21:43:46 INFO - 2015-11-20 21:43:46,609 DEBUG : Generating results file: dromaeo_css 21:43:46 INFO - 2015-11-20 21:43:46,610 DEBUG : Working with test: kraken 21:43:46 INFO - 2015-11-20 21:43:46,610 DEBUG : Generating results file: kraken 21:43:46 INFO - 2015-11-20 21:43:46,610 INFO : javascript benchmark 21:43:46 INFO - 2015-11-20 21:43:46,610 DEBUG : Working with test: v8_7 21:43:46 INFO - 2015-11-20 21:43:46,611 DEBUG : Generating results file: v8_7 21:43:46 INFO - 2015-11-20 21:43:46,611 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:43:46 INFO - 2015-11-20 21:43:46,657 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 21:43:46 INFO - 2015-11-20 21:43:46,657 DEBUG : process_Request line: dromaeo_css 7076.81 graph.html#tests=[[72,64,35]] 21:43:46 INFO - 2015-11-20 21:43:46,657 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:43:46 INFO - 2015-11-20 21:43:46,680 DEBUG : process_Request line: kraken 1499.70 graph.html#tests=[[232,64,35]] 21:43:46 INFO - 2015-11-20 21:43:46,680 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:43:46 INFO - 2015-11-20 21:43:46,713 DEBUG : process_Request line: v8_7 21045.00 graph.html#tests=[[230,64,35]] 21:43:46 INFO - 2015-11-20 21:43:46,714 INFO : javascript benchmark 21:43:46 INFO - 2015-11-20 21:43:46,718 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3219.903402897913, 3324.667533246677, 3353.0, 3375.5949286085674, 3379.35792199482, 5353.946460535393, 5486.698231597261, 5494.890102197954, 5538.9723051384735, 5570.220169176314, 5390.299261096062, 5462.399136095027, 5501.0648189807725, 5512.228288039668, 5549.417311182328, 4969.652124351297, 4993.151164302078, 5021.297018417425, 5043.6721613095215, 5099.566536844354, 4871.12319782439, 5086.79652813887, 5091.185410334348, 5102.923456148161, 5196.532312091911, 4985.900281994358, 4993.425756038066, 5037.370328708911, 5058.367704036996, 5059.696418214919, 5051.545360917498, 5066.620003499752, 5068.3917929848285, 5102.591792656615, 5127.8205262816, 4852.369192005052, 4950.7029578225065, 5032.924506132411, 5043.571296439807, 5046.5710414614805, 5243.764030618631, 5304.549113325372, 5326.0, 5351.0, 5356.758945847446, 5993.790217342372, 5994.910076348859, 5994.910076348859, 6000.909986350208, 6004.489618382443, 5752.050911599623, 5807.332156801979, 5861.6189947653265, 6024.578279520435, 6028.5478589106, 5506.614536982413, 5592.7762889484775, 5599.160125981054, 5603.495685388294, 5638.097904335286, 6075.787347442818, 6085.452309292096, 6097.688996865695, 6105.322780187562, 6107.328193898667, 5773.538116950718, 5823.155642431909, 5894.646321220741, 5951.940480595139, 5977.9402205977385, 5817.156512305692, 5860.2967643883, 5914.4381283777975, 5915.822525324247, 5966.522678185735, 5948.434898684618, 6056.727447264883, 6072.51419886408, 6073.817785466443, 6079.726412311456, 5824.94175058244, 5935.881282374328, 5949.553783466257, 6050.848728781815, 6076.969615151985, 4522.683412161183, 4549.727016379028, 4572.565606267399, 4586.403767510203, 4616.95383046172, 4095.877123686294, 4110.034141976633, 4117.444145040441, 4138.482689663792, 4142.06803469217, 5526.640768350045, 5641.069223578145, 5666.2350582670815, 5674.546036317085, 5677.205191273225, 5900.970495147497, 5959.612625179349, 5968.045112781934, 5976.5218782497295, 6022.156898034191, 5601.971990140023, 5634.211210430543, 5642.153676948408, 5647.491725744702, 5654.85862853432, 5036.269740887624, 5069.898602027939, 5195.5843532518065, 5199.090159222196, 5200.193969934666, 6094.512439004958, 6155.753769849156, 6160.10678451622, 6165.599236049642, 6175.0, 5953.161313390054, 5981.52147828164, 5981.7308221131025, 5983.910241346296, 6027.065804800263, 5965.552583556338, 6082.60463069899, 6084.787032453843, 6225.875482490415, 6233.376662333731, 5772.624779389326, 5871.0, 5891.764329426775, 5904.822855314347, 5910.911336329872, 5940.702964851654, 5974.970125149346, 5978.461938425563, 5991.161237426851, 6007.639541627516, 5666.518345940642, 5791.623544469596, 5835.912461312999, 5858.794942177004, 5860.941390586039, 5754.971225143848, 5765.250517432707, 5770.596058276048, 5773.018586840163, 5773.076307790733, 5683.3748287687495, 5685.829425117253, 5691.943080569307, 5692.459216374438, 5697.173909783141, 5702.144678298288, 5767.509761670305, 5801.419858014165, 5807.883842323045, 5808.041673123887, 5645.0, 5660.801871934463, 5687.971560142173, 5697.800576979786, 5701.914471282851], "unit": "ms", "name": "dojo.html", "value": 5477.491362673173}, {"lowerIsBetter": false, "replicates": [536.7772374464595, 596.8209537138857, 598.7365559153972, 610.6366711806473, 618.1068356225253, 37081.81459092704, 37506.0, 37548.624513754854, 37579.62420375796, 37583.24833503332, 41604.0, 41707.791461042725, 42041.78979105108, 42050.0, 42070.57929420705, 7722.150563438017, 7831.765047048598, 7847.489913155627, 8727.738167854974, 8905.154010369037, 10486.790264194711, 10718.732031699232, 10722.73193170173, 10741.946290268537, 10743.19426043046, 10370.170386369073, 10458.320209186379, 10474.947625261862, 10477.318974266687, 10494.317869338469, 7961.044674639052, 8127.634256458474, 8136.064352599408, 10252.897471025306, 10419.479026048743, 10311.43287119208, 10364.740881477948, 10462.319949203353, 10465.633702820365, 10471.84292235617, 39719.8014009931, 40290.0, 40318.39522407166, 40352.59647403518, 40355.394669079986, 3483.6168021517615, 3514.841832117561, 3541.978358442651, 3857.652811246973, 3936.094698219397, 30396.54405183924, 30554.84722576395, 30563.23591910198, 30592.0, 30962.53556196659, 3910.0, 3989.7007724420782, 4024.215278020787, 4078.4697989261504, 4186.811593478301, 273787.0, 276399.0, 276899.0, 276945.0, 277978.6101069502, 55281.44718552763, 55818.162727559116, 60092.699536502914, 63618.68190659017, 63921.68039159774, 56064.0, 56399.0, 56519.71740141273, 56752.0, 57056.0, 15849.683006339808, 15932.601684957737, 15995.360185592679, 16014.279357428944, 16016.839831601768, 11998.64004079879, 12132.211406258564, 12162.878371216351, 12186.81719774204, 12186.939065304618, 20331.390058298275, 20639.296773549275, 20651.380458586267, 20669.689954650694, 20704.689429658567, 7912.604369781488, 8045.15525869787, 8062.959685201537, 8100.513969161869, 8111.675532978733, 927.1655510041018, 937.0535758883576, 1004.5529739265957, 1095.3427943234155, 1149.8792626774236, 28333.57499637507, 28694.42611147807, 28837.134885953456, 29014.41971160565, 29017.709822901925, 1062.4475272858226, 1068.8289873620183, 1083.0739717541546, 1093.6008356949656, 1167.7897978363806, 14363.54730033693, 14404.639884002983, 14605.0, 14924.552263432115, 16423.507294781175, 411852.0, 415115.0, 415264.0, 416629.0, 418435.0, 53091.0, 53187.0, 53518.0, 53587.73206133944, 53697.46302537003, 1354.6748780292855, 1354.8916086713039, 1393.958181254564, 1411.9082259653296, 1418.7375335562954, 29874.415070191717, 30454.086377408712, 30590.235244119074, 30742.846285768428, 30749.385012299183, 43014.78492607517, 45661.0, 46193.0, 46204.537954621366, 46299.3055104167, 205930.0, 384709.0, 391962.0, 392042.0, 393350.0, 32028.0, 32682.0, 32831.83584082064, 32899.835500822344, 32949.0, 42385.78807105945, 42585.574144258164, 42892.57107429011, 43005.0, 43751.78124109359, 35589.46615800713, 35962.28075438424, 36380.27239455249, 36455.27089458249, 37087.0, 36635.0, 36734.44898326474, 37004.81497592495, 37333.4399983995, 37368.6263137376, 34514.82742586271, 40673.0, 40904.0, 41621.58378416177, 41655.58344416638, 30939.0718278452, 31071.68928310688, 33733.3253334927, 33842.15394615154, 33938.0, 25801.612975805, 26232.73767262303, 26573.7342626579, 27109.593356099278, 27371.452570948866], "unit": "ms", "name": "ext.html", "value": 19346.57902074462}, {"lowerIsBetter": false, "replicates": [358.2369552852426, 363.6200170821491, 364.42057129164624, 364.8777659484073, 368.67004031391906, 14175.574732758008, 14284.92857535711, 16817.0, 17294.82705172948, 17881.284748610055, 14856.405743770263, 14959.775603365932, 15028.0, 16025.83974160258, 17876.731849022275, 5328.8401347959625, 5500.559955203594, 5501.174823776446, 5502.257195278647, 5506.339239291291, 4117.238310912491, 4163.084121493267, 5428.782848686048, 5731.570132240078, 5922.141289513018, 5814.5057670097885, 5848.853778655525, 5850.180974663551, 5869.295684517842, 5885.558583106263, 5761.4814666679995, 5929.584929054968, 5930.466258036798, 5935.792247271346, 5937.287525496925, 5201.973990130063, 5242.6330156889035, 5254.92117618236, 5264.0, 5268.5521730652945, 19773.703394449094, 19848.10683519245, 20187.596248074955, 20273.290434834857, 20274.391768246976, 2063.9277625283194, 2082.6043051820257, 2128.8296936245065, 2136.2309568555306, 2163.610550100981, 8907.619319005564, 8970.686025989058, 9649.951750241275, 9741.415515069119, 10719.78560428787, 1764.4971183212722, 1827.7898041725107, 1828.4423250908467, 1831.6977698679702, 1901.91441385138, 62801.0, 62981.0, 63166.0, 66144.66927665379, 66328.6683566579, 10463.68608941733, 10466.110380617733, 10474.47627618616, 10511.264211505275, 10620.256582039183, 13623.25072121023, 13643.795343069862, 13664.111832730841, 13751.862481375058, 13758.174509529461, 7915.089764677021, 7924.48490848093, 8000.63997120131, 8068.798280043045, 8078.798030049178, 7772.222777722182, 7775.261350171724, 7787.727429539939, 7827.334676552557, 7827.45207835446, 3988.720789544762, 4182.56083111275, 4416.315471101984, 4532.161550113239, 4549.317602359606, 1725.7066298729246, 1761.7181251000036, 1780.5726625609761, 1814.7822261328724, 1849.6115815678588, 890.2699786175274, 921.424109931293, 956.6269155029544, 991.5736233419555, 1027.5530144387214, 14426.701051378737, 16347.0, 16562.66874662524, 16934.153292335333, 17874.910625446788, 884.9159329863653, 914.3553794574809, 916.0884919505135, 924.2882980105281, 975.3952549419405, 9740.366876153028, 9958.452285124244, 10026.19790416765, 10036.297459177933, 10048.69853904384, 554065.0, 554413.0, 560145.0, 573072.0, 575011.0, 78048.0, 78721.0, 80999.0, 82160.58919705363, 83038.0, 1172.9882701172878, 1175.3887978251262, 1186.8813118688236, 1190.2798806721983, 1194.3669854976877, 15498.380064797282, 15544.145072021147, 15758.921205394357, 15761.527154185393, 15775.830267499863, 36486.63513364832, 36690.08274793151, 37259.6274037267, 37575.43636845395, 37611.435828463145, 47564.52435475601, 47962.52037479581, 49024.50975490199, 50832.74583627058, 52403.0, 9160.954195228982, 9389.530523473935, 9626.95186524063, 9635.710928671871, 9683.854742178732, 11469.19715619932, 11549.42252887369, 11613.941930290295, 12925.806112908502, 17679.38122165718, 14229.715405692035, 14257.64355891111, 14422.134671919719, 14647.92676036613, 14662.63343416423, 14496.420143194155, 14679.85320146785, 14781.704365912407, 14846.480373187318, 15251.008684435476, 15212.771808422662, 15351.462698805488, 15422.383104675688, 15717.0, 15816.52550423489, 9541.570629321835, 9576.186024188022, 9682.661106861226, 9728.951355243178, 9742.805143896941, 14619.415223390944, 14782.186979716218, 14820.7776883349, 14972.0, 15171.772423413437], "unit": "ms", "name": "jquery.html", "value": 8976.957067721109}, {"lowerIsBetter": false, "replicates": [1538.2001359293174, 1689.096333461598, 1742.6950283700357, 1757.463973488086, 1780.0832571225822, 6705.765298214557, 7488.625568721569, 7649.349805266559, 7664.233576642333, 7673.769786906388, 7552.093748750158, 7664.041994750656, 7674.270944260286, 7676.616169191546, 7679.040119985002, 2296.5980953333183, 2323.082382458936, 2324.081987614891, 2324.6745455636224, 2324.8721320327377, 2324.5118525109747, 2345.179187284445, 2359.905603775847, 2363.669086327907, 2365.6806331145335, 2262.264763951714, 2295.2425699519204, 2297.4600968772324, 2298.6092364298024, 2308.2152068296778, 2284.5088306014204, 2286.0284379138798, 2303.355060583031, 2307.0310469602805, 2315.7684231576877, 2336.240721765433, 2337.0885354711677, 2350.753170917046, 2355.4464700795297, 2356.2106694257445, 6573.408393244583, 6687.261312058899, 7562.243775622449, 7571.621418929031, 7603.733869314603, 4220.831166753326, 4264.893377665552, 4289.2922667759785, 4428.446444194476, 4479.955200448019, 2966.362232120083, 3028.606281183454, 3068.7544996400234, 3080.1067690369764, 3112.859921303547, 976.9267304952086, 980.1717548671432, 998.236349192868, 1012.4886932099316, 1045.6758404894506, 10540.947295263551, 10587.205959552988, 10610.204234682353, 10616.57533698651, 10626.893731062744, 4244.427002354704, 4259.467566554181, 4286.142771445723, 4296.591823776736, 4367.71609845359, 9107.908920910706, 9159.313051521163, 9207.17135457812, 9215.493147876798, 9264.90735092654, 4731.69243999139, 4771.093492236464, 4772.0, 4773.164696178155, 4784.641151913551, 4307.1601037797955, 4322.524522302544, 4332.610065094157, 4356.912861742748, 4358.259095953696, 3602.2795440911923, 3651.94522082169, 3662.414013757786, 3670.33933891902, 3674.210044840319, 1143.9428028598536, 1150.5915400033034, 1151.6775302915198, 1153.3541216918384, 1157.5369852059243, 1037.5901518899993, 1062.5643486170634, 1062.9043386095288, 1230.1696354960366, 1383.688670049231, 7336.119665640157, 7347.632618369167, 7377.26227377258, 7426.443016773764, 7431.108267007994, 461.9168549661093, 464.4797826434415, 465.3043699669017, 471.2365967133266, 472.95743383095, 5397.4062853086125, 5752.9712351437975, 5847.239858818411, 5856.0630299151935, 5858.56060795442, 8483.830323393497, 8495.787605309917, 8609.0, 8650.783730406665, 8736.519491427905, 8594.828103437896, 8602.096779838152, 8722.389432739647, 8757.956210219036, 8889.688860889837, 7128.358447739728, 7151.177614574286, 7155.642217889084, 7181.999999999895, 7229.2047874733735, 6970.163580370388, 7045.718171273092, 7051.083359163276, 7053.153621565445, 7059.0, 7984.361251100015, 8185.959070204611, 8275.213854683796, 8304.958475207586, 8313.709020184264, 8199.467034642728, 8243.0520490142, 8258.710945116893, 8375.623096960773, 8384.413091083565, 7036.085308909809, 7118.572885626879, 7128.287171283037, 7130.572165670076, 7135.143782746104, 7513.0, 7568.659410326439, 7589.241075892367, 7616.961915190389, 7619.504732192389, 7192.0, 7212.927870721435, 7214.89177662346, 7281.2718728126765, 7307.5980821055355, 6901.206361268519, 7064.116985376828, 7077.115360579927, 7165.0327205828635, 7213.567185968858, 7176.067111275501, 7213.531120477152, 7218.963905180441, 7220.638968051471, 7225.421966242793, 6460.773872914426, 6537.084808126771, 6553.442957348679, 6591.0, 6616.636085015371, 6526.24948130952, 6563.606183628997, 6566.409023187936, 6569.408753212234, 6569.638669873012], "unit": "ms", "name": "mootools.html", "value": 4370.620628424722}, {"lowerIsBetter": false, "replicates": [348.43553443421655, 352.5980382364104, 353.5139183622519, 354.9325628130656, 358.6162805797796, 6048.092786082085, 6101.328853826087, 6106.053561697939, 6131.938680613192, 6144.416280453361, 5748.223989761381, 5822.767089316422, 5921.85195370116, 5982.132590774346, 6006.0, 4660.440747110351, 4661.300804879262, 4663.0, 4676.7427791471455, 4696.577308042276, 4445.088756804847, 4678.485366609671, 4681.274402467623, 4684.414448193976, 4689.20283551797, 4531.614812740921, 4533.410656614636, 4644.72131672099, 4649.163150632885, 4665.206914824471, 4619.792109355087, 4629.675922685414, 4634.559716826913, 4637.3275874997935, 4649.488556258809, 4091.447654566655, 4095.795210239506, 4101.753894766323, 4111.568285330027, 4117.135401565675, 5576.9163462548095, 5659.773609055633, 5668.716564171775, 5671.0, 5676.489115979541, 5329.493698098674, 5332.786688532454, 5335.546478549328, 5343.919841202385, 5350.678959262418, 6418.19772528434, 6430.903536446957, 6435.163428754278, 6438.420542151229, 6444.806655800334, 4450.799714012877, 4452.0205554777895, 4453.665975051852, 4459.821607135711, 4469.620082293009, 6505.674716264215, 6506.284308726036, 6628.73485060597, 6637.311888685091, 6645.734170633169, 4554.225781617133, 4554.7039442436135, 4554.908901821945, 4561.7262964222255, 4568.383268258809, 4607.424071991001, 4609.8156073757345, 4611.723296602215, 4616.699914505546, 4617.007343421114, 5305.575553955751, 5318.282031925641, 5325.227841962894, 5325.227841962971, 5329.600279979017, 6270.843228919313, 6459.580127291711, 6463.06285588587, 6468.450181734605, 6473.06140609609, 1619.627485678277, 1640.516047765912, 1641.4090927266175, 1642.0229963171894, 1642.4005238088012, 1076.9138468922467, 1083.772407794372, 1084.3331351218944, 1088.3361149698678, 1096.7313008313015, 6256.124142620037, 6271.749130034747, 6275.654838983808, 6283.340249273851, 6283.5287353448675, 6394.392532709385, 6636.2368327642935, 6646.036324732887, 6651.201855777241, 6662.0, 6126.448619624255, 6213.285472170758, 6279.058141278753, 6342.714577844008, 6351.809445716636, 3570.8928732137556, 3594.928101438009, 3616.9819150904077, 3619.3666108431444, 3619.7828130312264, 6754.696038678271, 6987.231404545496, 6989.161300643956, 6992.580445173306, 6995.440364770806, 6875.5530890492955, 6882.552634078769, 6894.482913781487, 6898.793036208922, 6901.551399159139, 6992.825179370557, 6999.0, 7000.229974702778, 7001.614911179934, 7002.124734408199, 6560.704768285344, 6698.095757072928, 6698.363655452724, 6700.329967003261, 6701.765438209639, 6644.368784965421, 6648.867022659423, 6657.40083392497, 6658.400743933069, 6660.83347916306, 6972.1284839395075, 6977.755778547727, 6981.232064472802, 6994.440444764305, 7001.754938577125, 4454.710443821141, 4504.414426124583, 4504.954950450454, 4505.279155335131, 4509.098180363875, 6694.59832410057, 6703.530752847254, 6707.698153583003, 6711.664416779044, 6715.865682686222, 6473.514486413444, 6496.285408605144, 6497.447716943923, 6503.382178693017, 6518.674066296761, 6380.266269379081, 6485.805425837232, 6490.967545162244, 6493.74025039012, 6501.707423165874, 6376.234851817626, 6386.361363863576, 6387.808365749035, 6391.648459334595, 6403.743850245938, 6495.480361571157, 6508.446782023581, 6509.8698026040165, 6511.218653761579, 6524.771632992823, 6215.098810672337, 6381.3937675920715, 6383.585066970632, 6383.744650213939, 6400.0], "unit": "ms", "name": "prototype.html", "value": 4872.259161618741}, {"lowerIsBetter": false, "replicates": [847.51268020888, 851.569957171629, 856.1737922904398, 858.6393714639847, 866.0603245478655, 12948.287844168566, 13818.723625527497, 13949.302534873266, 13973.860261397396, 14007.579772606812, 12809.871901280985, 12900.09699321048, 12901.1614245074, 12905.22568645882, 12921.289329086896, 672.6434989455596, 677.7289084366244, 824.46821799939, 838.2581415447312, 838.5010918503481, 834.9958250208771, 838.8909441772591, 847.1655419411875, 849.235687880909, 849.6304107713164, 846.2637505370336, 849.5285116760195, 850.3324889961382, 854.8204876975842, 856.1267507142711, 705.9576425414466, 710.9111361079865, 712.0245263988343, 716.2550947015098, 838.4801423117675, 627.0, 628.8176428835632, 630.1461519640867, 631.4790298004165, 634.4416913116428, 12095.153339266255, 12360.381980900918, 12364.690882727913, 12369.257844529267, 12380.0, 11657.650270491898, 12150.0, 12194.207376520499, 12355.073369497324, 12362.75274494505, 10509.369437833755, 10711.732206694816, 10712.78574428515, 10790.244682872202, 10805.945970270177, 3633.6003039665616, 3682.2819550187724, 3711.6288371162937, 3803.4675145479655, 3807.276617442677, 44813.327800083025, 45097.77451112724, 45101.54898451006, 45204.0, 45255.547444525466, 9971.551280192263, 10015.599376025024, 10125.949370253249, 10202.846957295647, 10418.06237438619, 17680.381186658404, 18225.362112326005, 18231.817681823013, 18259.178336974866, 18261.63476730458, 7722.652480638385, 7752.108507521707, 7776.1057478389575, 7777.92222077772, 7951.721689740832, 4414.381986521889, 4450.732956022649, 4466.0, 4581.94615238497, 4642.326862604903, 2968.732814046746, 2985.149232468736, 2985.268609190762, 2989.0, 2993.670696223414, 565.3470241870646, 579.7043507811047, 582.4786815799836, 586.0857062981762, 587.0753563138074, 512.4772731813604, 542.1704791668753, 599.2120361724319, 630.5523078614143, 643.0386572074684, 8038.557879316693, 8279.0, 8480.321574274043, 8570.185832346042, 8590.269827064769, 466.4123204762024, 563.9436056394328, 573.724612186153, 587.1369087441454, 652.0870780906769, 3546.4325707886615, 3603.6756691897854, 3633.9818300908855, 3642.8907132786057, 3860.4595356650093, 56273.7186314074, 56979.715101424226, 57059.0, 57325.14012289819, 57565.42434575601, 50029.49970500321, 50148.24777628339, 50526.24210636843, 50871.0, 51333.23000155001, 45229.54770452319, 45370.31944520835, 46051.53948460473, 46066.76966615146, 46622.5337746625, 7834.138244793182, 8204.712835050745, 8366.079731229682, 8623.267022302924, 8901.955490222508, 33376.66623333735, 33968.0, 34097.0, 34366.0, 35686.28627427489, 43189.7840510808, 43980.34029489624, 44599.55400445954, 44769.77615111903, 45037.32444013277, 10483.895161048596, 10603.734906627396, 10684.786304274026, 10699.518521666389, 11109.11127109813, 11973.16187866841, 11998.446803002791, 12079.57721479744, 12109.33398663082, 12802.67993300175, 10831.729206769895, 11111.611093611686, 11182.888171118184, 11323.32060076398, 11378.203525753448, 10607.257491975488, 10790.352578845295, 11293.153013523854, 11302.152338574475, 11786.528538858349, 10949.278203174772, 10960.835587466036, 11050.723731906766, 11282.887171128184, 11303.095752339957, 9872.802543948937, 10238.18094552449, 10251.487425628839, 10304.330218535772, 10662.786744264917, 8302.684673685, 8711.0, 8795.868061979203, 8969.686060987835, 9464.148226659634], "unit": "ms", "name": "yui.html", "value": 6200.0426159070485}], "name": "dromaeo_css", "value": 7076.80572161837}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 106.0, 111.0, 112.0, 110.0, 99.0, 109.0, 115.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 109.1}, {"lowerIsBetter": true, "replicates": [150.0, 124.0, 149.0, 160.0, 126.0, 155.0, 146.0, 126.0, 169.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.0}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 162.0, 162.0, 161.0, 162.0, 162.0, 163.0, 161.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 162.1}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 82.0, 92.0, 72.0, 72.0, 72.0, 98.0, 91.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 79.4}, {"lowerIsBetter": true, "replicates": [91.0, 90.0, 135.0, 90.0, 90.0, 90.0, 144.0, 90.0, 91.0, 130.0], "unit": "ms", "name": "audio-oscillator", "value": 104.1}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [59.0, 61.0, 58.0, 56.0, 57.0, 57.0, 57.0, 65.0, 54.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.9}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 69.0, 69.0, 69.0, 68.0, 69.0, 71.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.1}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 86.0, 86.0, 85.0, 87.0, 87.0, 86.0, 95.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.7}, {"lowerIsBetter": true, "replicates": [131.0, 122.0, 116.0, 117.0, 115.0, 127.0, 115.0, 117.0, 125.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.0}, {"lowerIsBetter": true, "replicates": [143.0, 145.0, 156.0, 158.0, 150.0, 141.0, 161.0, 152.0, 154.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 150.2}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 66.0, 66.0, 67.0, 68.0, 66.0, 68.0, 67.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.1}], "name": "kraken", "value": 1499.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.86644951140065], "unit": "score", "name": "Richards", "value": 216.75427999999994}, {"lowerIsBetter": false, "replicates": [188.07598269700958], "unit": "score", "name": "DeltaBlue", "value": 351.549406}, {"lowerIsBetter": false, "replicates": [281.214848143982], "unit": "score", "name": "Encrypt", "value": 946.5396360000002}, {"lowerIsBetter": false, "replicates": [5025.125628140703], "unit": "score", "name": "Decrypt", "value": 52.970019}, {"lowerIsBetter": false, "replicates": [1107.4197120708748], "unit": "score", "name": "RayTrace", "value": 668.2100670000003}, {"lowerIsBetter": false, "replicates": [783.6990595611285], "unit": "score", "name": "Earley", "value": 850.4067880000003}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [25575.0], "unit": "score", "name": "RegExp", "value": 35.620136852394914}, {"lowerIsBetter": false, "replicates": [645.1612903225806], "unit": "score", "name": "Splay", "value": 126.31105000000005}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 21044.998734841214}]} 21:43:46 INFO - RETURN: dromaeo_css: 7076.81 21:43:46 INFO - RETURN: kraken: 1499.70 21:43:46 INFO - RETURN: v8_7: 21045.00 21:43:46 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "21045.00"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1499.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "7076.81"}}} 21:43:46 INFO - Return code: 0 21:43:46 INFO - # TBPL SUCCESS # 21:43:46 INFO - Running post-action listener: _resource_record_post_action 21:43:46 INFO - Running post-run listener: _resource_record_post_run 21:43:47 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506720256; Read time: 20; Write time: 584848 21:43:47 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 227471360; Read time: 0; Write time: 309552 21:43:47 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 264060928; Read time: 20; Write time: 159524 21:43:47 INFO - Running post-run listener: _upload_blobber_files 21:43:47 INFO - Blob upload gear active. 21:43:47 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 21:43:47 INFO - Copying logs to upload dir... 21:43:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.894912 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2015-11-20 21:43:46.608572) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 21:43:46.612141) ========= 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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009101 build_url: 'https://queue.taskcluster.net/v1/task/h0S20jQ-QymtX058KVDJWw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 21:43:46.661067) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:43:46.661393) ========= 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=004b26eee9c3b360c8654f40000001e1-1448078804.189928-2129584143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 21:43:46.710299) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 21:43:46.710574) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 21:43:46.710854) ========= ========= Total master_lag: 0.23 =========