builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-010 starttime: 1448408493.3 results: success (0) buildid: 20151124143004 builduid: b9ff8266b246414bb02ed846846d3dd8 revision: 8a3e98127ebc28fcc01d27bdedb4c959a456c47d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.303297) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.303691) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.303976) ========= 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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006680 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.322802) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.323087) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.330818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.331132) ========= 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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004278 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.383540) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.384105) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.384486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.384750) ========= 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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 15:41:33-- 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% 223M=0s 2015-11-24 15:41:33 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110122 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.509811) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.510086) ========= 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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022316 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.558891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:33.559243) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8a3e98127ebc28fcc01d27bdedb4c959a456c47d --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 8a3e98127ebc28fcc01d27bdedb4c959a456c47d --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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 15:41:33,834 truncating revision to first 12 chars 2015-11-24 15:41:33,834 Setting DEBUG logging. 2015-11-24 15:41:33,835 attempt 1/10 2015-11-24 15:41:33,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8a3e98127ebc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 15:41:34,100 unpacking tar archive at: fx-team-8a3e98127ebc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.568231 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:34.142366) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:34.142679) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:34.154932) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:34.155237) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 15:41:34.155657) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-24 15:41:34.155935) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:41:34 INFO - MultiFileLogger online at 20151124 15:41:34 in /builds/slave/test-pgo 15:41:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 15:41:34 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:41:34 INFO - {'append_to_log': False, 15:41:34 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:41:34 INFO - 'blob_upload_branch': 'Fx-Team', 15:41:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:41:34 INFO - 'branch': 'Fx-Team', 15:41:34 INFO - 'buildbot_json_path': 'buildprops.json', 15:41:34 INFO - 'config_files': ('talos/linux_config.py',), 15:41:34 INFO - 'default_actions': ('clobber', 15:41:34 INFO - 'read-buildbot-config', 15:41:34 INFO - 'download-and-extract', 15:41:34 INFO - 'populate-webroot', 15:41:34 INFO - 'create-virtualenv', 15:41:34 INFO - 'install', 15:41:34 INFO - 'run-tests'), 15:41:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:41:34 INFO - 'download_minidump_stackwalk': True, 15:41:34 INFO - 'download_symbols': 'ondemand', 15:41:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:41:34 INFO - 'tooltool.py': '/tools/tooltool.py', 15:41:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:41:34 INFO - '/tools/misc-python/virtualenv.py')}, 15:41:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:41:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:41:34 INFO - 'installer_path': 'installer.exe', 15:41:34 INFO - 'log_level': 'info', 15:41:34 INFO - 'log_name': 'talos', 15:41:34 INFO - 'log_to_console': True, 15:41:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:41:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:41:34 INFO - 'opt_config_files': (), 15:41:34 INFO - 'pip_index': False, 15:41:34 INFO - 'sps_profile': False, 15:41:34 INFO - 'sps_profile_interval': 0, 15:41:34 INFO - 'suite': 'dromaeojs-e10s', 15:41:34 INFO - 'system_bits': '64', 15:41:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:41:34 INFO - 'title': 'talos-linux64-ix-010', 15:41:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:41:34 INFO - 'use_talos_json': True, 15:41:34 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:41:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:41:34 INFO - 'work_dir': 'build'} 15:41:34 INFO - ##### 15:41:34 INFO - ##### Running clobber step. 15:41:34 INFO - ##### 15:41:34 INFO - Running pre-action listener: _resource_record_pre_action 15:41:34 INFO - Running main action method: clobber 15:41:34 INFO - rmtree: /builds/slave/test-pgo/build 15:41:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:41:35 INFO - Running post-action listener: _resource_record_post_action 15:41:35 INFO - ##### 15:41:35 INFO - ##### Running read-buildbot-config step. 15:41:35 INFO - ##### 15:41:35 INFO - Running pre-action listener: _resource_record_pre_action 15:41:35 INFO - Running main action method: read_buildbot_config 15:41:35 INFO - Using buildbot properties: 15:41:35 INFO - { 15:41:35 INFO - "properties": { 15:41:35 INFO - "buildnumber": 107, 15:41:35 INFO - "product": "firefox", 15:41:35 INFO - "script_repo_revision": "production", 15:41:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 15:41:35 INFO - "repository": "", 15:41:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 15:41:35 INFO - "buildid": "20151124143004", 15:41:35 INFO - "slavename": "talos-linux64-ix-010", 15:41:35 INFO - "pgo_build": "True", 15:41:35 INFO - "basedir": "/builds/slave/test-pgo", 15:41:35 INFO - "project": "", 15:41:35 INFO - "platform": "ubuntu64_hw", 15:41:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:41:35 INFO - "slavebuilddir": "test", 15:41:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 15:41:35 INFO - "branch": "fx-team", 15:41:35 INFO - "repo_path": "integration/fx-team", 15:41:35 INFO - "stage_platform": "linux64-pgo", 15:41:35 INFO - "builduid": "b9ff8266b246414bb02ed846846d3dd8", 15:41:35 INFO - "revision": "8a3e98127ebc28fcc01d27bdedb4c959a456c47d" 15:41:35 INFO - }, 15:41:35 INFO - "sourcestamp": { 15:41:35 INFO - "repository": "", 15:41:35 INFO - "hasPatch": false, 15:41:35 INFO - "project": "", 15:41:35 INFO - "branch": "fx-team-linux64-pgo-talos", 15:41:35 INFO - "changes": [ 15:41:35 INFO - { 15:41:35 INFO - "category": null, 15:41:35 INFO - "files": [ 15:41:35 INFO - { 15:41:35 INFO - "url": null, 15:41:35 INFO - "name": "https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 15:41:35 INFO - } 15:41:35 INFO - ], 15:41:35 INFO - "repository": "", 15:41:35 INFO - "rev": "8a3e98127ebc28fcc01d27bdedb4c959a456c47d", 15:41:35 INFO - "who": "sendchange", 15:41:35 INFO - "when": 1448408434, 15:41:35 INFO - "number": 6702463, 15:41:35 INFO - "comments": "", 15:41:35 INFO - "project": "", 15:41:35 INFO - "at": "Tue 24 Nov 2015 15:40:34", 15:41:35 INFO - "branch": "fx-team-linux64-pgo-talos", 15:41:35 INFO - "revlink": "", 15:41:35 INFO - "properties": [ 15:41:35 INFO - [ 15:41:35 INFO - "buildid", 15:41:35 INFO - "20151124143004", 15:41:35 INFO - "Change" 15:41:35 INFO - ], 15:41:35 INFO - [ 15:41:35 INFO - "builduid", 15:41:35 INFO - "b9ff8266b246414bb02ed846846d3dd8", 15:41:35 INFO - "Change" 15:41:35 INFO - ], 15:41:35 INFO - [ 15:41:35 INFO - "pgo_build", 15:41:35 INFO - "True", 15:41:35 INFO - "Change" 15:41:35 INFO - ] 15:41:35 INFO - ], 15:41:35 INFO - "revision": "8a3e98127ebc28fcc01d27bdedb4c959a456c47d" 15:41:35 INFO - } 15:41:35 INFO - ], 15:41:35 INFO - "revision": "8a3e98127ebc28fcc01d27bdedb4c959a456c47d" 15:41:35 INFO - } 15:41:35 INFO - } 15:41:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 15:41:35 INFO - Running post-action listener: _resource_record_post_action 15:41:35 INFO - ##### 15:41:35 INFO - ##### Running download-and-extract step. 15:41:35 INFO - ##### 15:41:35 INFO - Running pre-action listener: _resource_record_pre_action 15:41:35 INFO - Running main action method: download_and_extract 15:41:35 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:41:35 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')]} 15:41:35 INFO - https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:41:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/test_packages.json 15:41:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/test_packages.json 15:41:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:41:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:41:41 INFO - Downloaded 1302 bytes. 15:41:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:41:41 INFO - Using the following test package requirements: 15:41:41 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:41:41 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:41:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:41:41 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:41:41 INFO - u'jsshell-linux-x86_64.zip'], 15:41:41 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:41:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:41:41 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:41:41 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:41:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:41:41 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:41:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:41:41 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:41:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:41:41 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 15:41:41 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 15:41:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:41:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:41 INFO - https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:41:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:41:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:41:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:42 INFO - Downloaded 22526274 bytes. 15:41:42 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 15:41:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:41:42 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 15:41:43 INFO - Return code: 0 15:41:43 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 15:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:43 INFO - https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:41:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:41:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:41:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:47 INFO - Downloaded 22526274 bytes. 15:41:47 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 15:41:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 15:41:47 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 15:41:48 INFO - Return code: 0 15:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:48 INFO - https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:41:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:41:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:41:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:49 INFO - Downloaded 11130997 bytes. 15:41:49 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 15:41:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 15:41:49 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 15:41:49 INFO - Return code: 0 15:41:49 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')]} 15:41:49 INFO - https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:41:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:41:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:41:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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 15:41:50 INFO - Downloaded 57640729 bytes. 15:41:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:41:50 INFO - mkdir: /builds/slave/test-pgo/properties 15:41:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:41:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:41:50 INFO - Contents: 15:41:50 INFO - build_url:https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 15:41:50 INFO - Running post-action listener: _resource_record_post_action 15:41:50 INFO - Running post-action listener: set_extra_try_arguments 15:41:50 INFO - ##### 15:41:50 INFO - ##### Running populate-webroot step. 15:41:50 INFO - ##### 15:41:50 INFO - Running pre-action listener: _resource_record_pre_action 15:41:50 INFO - Running main action method: populate_webroot 15:41:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:41:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:41:50 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 15:41:50 INFO - '../fennec_ids.txt'], 15:41:50 INFO - 'tests': ['tcheck2']}, 15:41:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:41:50 INFO - '--tppagecycles', 15:41:50 INFO - '7'], 15:41:50 INFO - 'tests': ['tsvgm']}}, 15:41:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:41:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:41:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:41:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:41:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'tests': ['damp', 'tps']}, 15:41:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['damp', 'tps']}, 15:41:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:41:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:41:50 INFO - 'other': {'tests': ['a11yr', 15:41:50 INFO - 'ts_paint', 15:41:50 INFO - 'tpaint', 15:41:50 INFO - 'sessionrestore', 15:41:50 INFO - 'sessionrestore_no_auto_restore']}, 15:41:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['a11yr', 15:41:50 INFO - 'ts_paint', 15:41:50 INFO - 'tpaint', 15:41:50 INFO - 'sessionrestore', 15:41:50 INFO - 'sessionrestore_no_auto_restore']}, 15:41:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['a11yr', 15:41:50 INFO - 'ts_paint', 15:41:50 INFO - 'tpaint', 15:41:50 INFO - 'sessionrestore', 15:41:50 INFO - 'sessionrestore_no_auto_restore']}, 15:41:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['a11yr', 15:41:50 INFO - 'ts_paint', 15:41:50 INFO - 'tpaint', 15:41:50 INFO - 'sessionrestore', 15:41:50 INFO - 'sessionrestore_no_auto_restore']}, 15:41:50 INFO - 'other_l64': {'tests': ['a11yr', 15:41:50 INFO - 'ts_paint', 15:41:50 INFO - 'tpaint', 15:41:50 INFO - 'sessionrestore', 15:41:50 INFO - 'sessionrestore_no_auto_restore']}, 15:41:50 INFO - 'other_nol64': {'tests': ['a11yr', 15:41:50 INFO - 'ts_paint', 15:41:50 INFO - 'tpaint', 15:41:50 INFO - 'sessionrestore', 15:41:50 INFO - 'sessionrestore_no_auto_restore']}, 15:41:50 INFO - 'svgr': {'tests': ['tsvgx', 15:41:50 INFO - 'tsvgr_opacity', 15:41:50 INFO - 'tart', 15:41:50 INFO - 'tscrollx', 15:41:50 INFO - 'cart']}, 15:41:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['tsvgx', 15:41:50 INFO - 'tsvgr_opacity', 15:41:50 INFO - 'tart', 15:41:50 INFO - 'tscrollx', 15:41:50 INFO - 'cart']}, 15:41:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'tests': ['tp5o']}, 15:41:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'talos_options': ['--e10s'], 15:41:50 INFO - 'tests': ['tp5o']}, 15:41:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'talos_options': ['--xperf_path', 15:41:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:50 INFO - 'tests': ['tp5n']}, 15:41:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:50 INFO - 'talos_options': ['--e10s', 15:41:50 INFO - '--xperf_path', 15:41:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:50 INFO - 'tests': ['tp5n']}}, 15:41:50 INFO - 'talos.zip': {'path': '', 15:41:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:41:50 INFO - Running post-action listener: _resource_record_post_action 15:41:50 INFO - ##### 15:41:50 INFO - ##### Running create-virtualenv step. 15:41:50 INFO - ##### 15:41:50 INFO - Running pre-action listener: _resource_record_pre_action 15:41:50 INFO - Running main action method: create_virtualenv 15:41:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:41:50 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 15:41:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:41:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:41:51 INFO - Using real prefix '/usr' 15:41:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:41:51 INFO - Installing distribute.............................................................................................................................................................................................done. 15:41:53 INFO - Installing pip.................done. 15:41:53 INFO - Return code: 0 15:41:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:53 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 0x7ffaa7c15c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, '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': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', '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 15:41:53 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 15:41:53 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 15:41:53 INFO - Using env: {'DISPLAY': ':0', 15:41:53 INFO - 'HOME': '/home/cltbld', 15:41:53 INFO - 'LANG': 'en_US.UTF-8', 15:41:53 INFO - 'LANGUAGE': 'en_US:en', 15:41:53 INFO - 'LOGNAME': 'cltbld', 15:41:53 INFO - 'MAIL': '/var/mail/cltbld', 15:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:53 INFO - 'MOZ_NO_REMOTE': '1', 15:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:53 INFO - 'NO_EM_RESTART': '1', 15:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:41:53 INFO - 'PWD': '/builds/slave/test-pgo', 15:41:53 INFO - 'SHELL': '/bin/bash', 15:41:53 INFO - 'SHLVL': '1', 15:41:53 INFO - 'TERM': 'linux', 15:41:53 INFO - 'TMOUT': '86400', 15:41:53 INFO - 'USER': 'cltbld', 15:41:53 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:53 INFO - '_': '/tools/buildbot/bin/python'} 15:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:53 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 15:41:53 INFO - Cleaning up... 15:41:53 INFO - Return code: 0 15:41:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:53 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 0x7ffaa7c15c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, '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': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', '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 15:41:53 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 15:41:53 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 15:41:53 INFO - Using env: {'DISPLAY': ':0', 15:41:53 INFO - 'HOME': '/home/cltbld', 15:41:53 INFO - 'LANG': 'en_US.UTF-8', 15:41:53 INFO - 'LANGUAGE': 'en_US:en', 15:41:53 INFO - 'LOGNAME': 'cltbld', 15:41:53 INFO - 'MAIL': '/var/mail/cltbld', 15:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:53 INFO - 'MOZ_NO_REMOTE': '1', 15:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:53 INFO - 'NO_EM_RESTART': '1', 15:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:41:53 INFO - 'PWD': '/builds/slave/test-pgo', 15:41:53 INFO - 'SHELL': '/bin/bash', 15:41:53 INFO - 'SHLVL': '1', 15:41:53 INFO - 'TERM': 'linux', 15:41:53 INFO - 'TMOUT': '86400', 15:41:53 INFO - 'USER': 'cltbld', 15:41:53 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:53 INFO - '_': '/tools/buildbot/bin/python'} 15:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:54 INFO - Downloading/unpacking psutil>=0.7.1 15:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 15:41:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:41:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:41:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:55 INFO - Installing collected packages: psutil 15:41:55 INFO - Running setup.py install for psutil 15:41:55 INFO - building 'psutil._psutil_linux' extension 15:41:55 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 15:41:56 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 15:41:56 INFO - building 'psutil._psutil_posix' extension 15:41:56 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 15:41:56 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 15:41:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:56 INFO - Successfully installed psutil 15:41:56 INFO - Cleaning up... 15:41:56 INFO - Return code: 0 15:41:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:56 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 0x7ffaa7c15c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, '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': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', '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 15:41:56 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 15:41:56 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 15:41:56 INFO - Using env: {'DISPLAY': ':0', 15:41:56 INFO - 'HOME': '/home/cltbld', 15:41:56 INFO - 'LANG': 'en_US.UTF-8', 15:41:56 INFO - 'LANGUAGE': 'en_US:en', 15:41:56 INFO - 'LOGNAME': 'cltbld', 15:41:56 INFO - 'MAIL': '/var/mail/cltbld', 15:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:56 INFO - 'MOZ_NO_REMOTE': '1', 15:41:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:56 INFO - 'NO_EM_RESTART': '1', 15:41:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:41:56 INFO - 'PWD': '/builds/slave/test-pgo', 15:41:56 INFO - 'SHELL': '/bin/bash', 15:41:56 INFO - 'SHLVL': '1', 15:41:56 INFO - 'TERM': 'linux', 15:41:56 INFO - 'TMOUT': '86400', 15:41:56 INFO - 'USER': 'cltbld', 15:41:56 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:56 INFO - '_': '/tools/buildbot/bin/python'} 15:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:41:56 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 15:41:56 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 15:41:56 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 15:41:56 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 15:41:56 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 15:41:56 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 15:41:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:41:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:41:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:41:58 INFO - Installing collected packages: mozsystemmonitor 15:41:58 INFO - Running setup.py install for mozsystemmonitor 15:41:58 INFO - Successfully installed mozsystemmonitor 15:41:58 INFO - Cleaning up... 15:41:58 INFO - Return code: 0 15:41:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:41:58 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')]} 15:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:58 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')]} 15:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:58 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 0x7ffaa7c15c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, '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': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', '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 15:41:58 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 15:41:58 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 15:41:58 INFO - Using env: {'DISPLAY': ':0', 15:41:58 INFO - 'HOME': '/home/cltbld', 15:41:58 INFO - 'LANG': 'en_US.UTF-8', 15:41:58 INFO - 'LANGUAGE': 'en_US:en', 15:41:58 INFO - 'LOGNAME': 'cltbld', 15:41:58 INFO - 'MAIL': '/var/mail/cltbld', 15:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:58 INFO - 'MOZ_NO_REMOTE': '1', 15:41:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:58 INFO - 'NO_EM_RESTART': '1', 15:41:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:41:58 INFO - 'PWD': '/builds/slave/test-pgo', 15:41:58 INFO - 'SHELL': '/bin/bash', 15:41:58 INFO - 'SHLVL': '1', 15:41:58 INFO - 'TERM': 'linux', 15:41:58 INFO - 'TMOUT': '86400', 15:41:58 INFO - 'USER': 'cltbld', 15:41:58 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:58 INFO - '_': '/tools/buildbot/bin/python'} 15:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:58 INFO - Downloading/unpacking blobuploader==1.2.4 15:41:58 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 15:41:58 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 15:41:58 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 15:41:58 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 15:41:58 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 15:41:58 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 15:42:00 INFO - Downloading blobuploader-1.2.4.tar.gz 15:42:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:42:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:42:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:42:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:42:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:01 INFO - Downloading docopt-0.6.1.tar.gz 15:42:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:42:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:42:01 INFO - Installing collected packages: blobuploader, requests, docopt 15:42:01 INFO - Running setup.py install for blobuploader 15:42:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:42:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:42:01 INFO - Running setup.py install for requests 15:42:01 INFO - Running setup.py install for docopt 15:42:01 INFO - Successfully installed blobuploader requests docopt 15:42:01 INFO - Cleaning up... 15:42:01 INFO - Return code: 0 15:42:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:42:01 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')]} 15:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:01 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')]} 15:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:01 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 0x7ffaa7c15c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, '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': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', '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 15:42:01 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 15:42:01 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 15:42:01 INFO - Using env: {'DISPLAY': ':0', 15:42:01 INFO - 'HOME': '/home/cltbld', 15:42:01 INFO - 'LANG': 'en_US.UTF-8', 15:42:01 INFO - 'LANGUAGE': 'en_US:en', 15:42:01 INFO - 'LOGNAME': 'cltbld', 15:42:01 INFO - 'MAIL': '/var/mail/cltbld', 15:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:01 INFO - 'MOZ_NO_REMOTE': '1', 15:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:01 INFO - 'NO_EM_RESTART': '1', 15:42:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:42:01 INFO - 'PWD': '/builds/slave/test-pgo', 15:42:01 INFO - 'SHELL': '/bin/bash', 15:42:01 INFO - 'SHLVL': '1', 15:42:01 INFO - 'TERM': 'linux', 15:42:01 INFO - 'TMOUT': '86400', 15:42:01 INFO - 'USER': 'cltbld', 15:42:01 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:01 INFO - '_': '/tools/buildbot/bin/python'} 15:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:42:01 INFO - Running setup.py (path:/tmp/pip-4LqsKD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:42:02 INFO - Running setup.py (path:/tmp/pip-CnGQ8r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:42:02 INFO - Running setup.py (path:/tmp/pip-VDGSHQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:42:02 INFO - Running setup.py (path:/tmp/pip-Wff6He-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:42:02 INFO - Running setup.py (path:/tmp/pip-G2ntIp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:42:02 INFO - Running setup.py (path:/tmp/pip-fZzZkn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:42:02 INFO - Running setup.py (path:/tmp/pip-LWFhpe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:42:02 INFO - Running setup.py (path:/tmp/pip-mbC49S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:42:02 INFO - Running setup.py (path:/tmp/pip-gvQjJz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:42:02 INFO - Running setup.py (path:/tmp/pip-U7ZfyA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:42:02 INFO - Running setup.py (path:/tmp/pip-qNahG1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:42:02 INFO - Running setup.py (path:/tmp/pip-QNRJzm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:42:02 INFO - Running setup.py (path:/tmp/pip-qWpo1u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:42:02 INFO - Running setup.py (path:/tmp/pip-AuZV9K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:42:02 INFO - Running setup.py (path:/tmp/pip-CuwuJt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:42:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:42:03 INFO - Running setup.py (path:/tmp/pip-cyHi74-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:42:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:42:03 INFO - Running setup.py (path:/tmp/pip-DByY3r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:42:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:42:03 INFO - Running setup.py install for manifestparser 15:42:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Running setup.py install for mozcrash 15:42:03 INFO - Running setup.py install for mozdebug 15:42:03 INFO - Running setup.py install for mozdevice 15:42:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Running setup.py install for mozfile 15:42:03 INFO - Running setup.py install for mozhttpd 15:42:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Running setup.py install for mozinfo 15:42:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Running setup.py install for mozInstall 15:42:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:42:03 INFO - Running setup.py install for mozleak 15:42:04 INFO - Running setup.py install for mozlog 15:42:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:42:04 INFO - Running setup.py install for moznetwork 15:42:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:42:04 INFO - Running setup.py install for mozprocess 15:42:04 INFO - Running setup.py install for mozprofile 15:42:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:42:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:42:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:42:04 INFO - Running setup.py install for mozrunner 15:42:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:42:04 INFO - Running setup.py install for mozscreenshot 15:42:04 INFO - Running setup.py install for moztest 15:42:04 INFO - Running setup.py install for mozversion 15:42:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:42:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:42:04 INFO - Cleaning up... 15:42:04 INFO - Return code: 0 15:42:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:42:04 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')]} 15:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:04 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')]} 15:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:04 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 0x7ffaa7c15c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, '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': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', '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 15:42:04 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 15:42:04 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 15:42:04 INFO - Using env: {'DISPLAY': ':0', 15:42:04 INFO - 'HOME': '/home/cltbld', 15:42:04 INFO - 'LANG': 'en_US.UTF-8', 15:42:04 INFO - 'LANGUAGE': 'en_US:en', 15:42:04 INFO - 'LOGNAME': 'cltbld', 15:42:04 INFO - 'MAIL': '/var/mail/cltbld', 15:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:04 INFO - 'MOZ_NO_REMOTE': '1', 15:42:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:04 INFO - 'NO_EM_RESTART': '1', 15:42:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:42:04 INFO - 'PWD': '/builds/slave/test-pgo', 15:42:04 INFO - 'SHELL': '/bin/bash', 15:42:04 INFO - 'SHLVL': '1', 15:42:04 INFO - 'TERM': 'linux', 15:42:04 INFO - 'TMOUT': '86400', 15:42:04 INFO - 'USER': 'cltbld', 15:42:04 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:04 INFO - '_': '/tools/buildbot/bin/python'} 15:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:42:05 INFO - Running setup.py (path:/tmp/pip-6W82ab-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:42:05 INFO - Running setup.py (path:/tmp/pip-CoQuvu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:42:05 INFO - Running setup.py (path:/tmp/pip-tb0MwL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:42:05 INFO - Running setup.py (path:/tmp/pip-KXQwIK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:42:05 INFO - Running setup.py (path:/tmp/pip-jOAK6d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:42:05 INFO - Running setup.py (path:/tmp/pip-3xSCKz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:42:05 INFO - Running setup.py (path:/tmp/pip-Chkzyy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:42:05 INFO - Running setup.py (path:/tmp/pip-4DF4_n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:42:05 INFO - Running setup.py (path:/tmp/pip-HnQe9I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:42:05 INFO - Running setup.py (path:/tmp/pip-2650hR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:42:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:42:05 INFO - Running setup.py (path:/tmp/pip-JzFKSX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:42:05 INFO - Running setup.py (path:/tmp/pip-yUNJvf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:42:05 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)) 15:42:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:42:05 INFO - Running setup.py (path:/tmp/pip-_rHGwy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:42:06 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)) 15:42:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:42:06 INFO - Running setup.py (path:/tmp/pip-PdGi8s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:42:06 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)) 15:42:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:42:06 INFO - Running setup.py (path:/tmp/pip-hd__Mk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:42:06 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)) 15:42:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:42:06 INFO - Running setup.py (path:/tmp/pip-3TlldA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:42:06 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)) 15:42:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:42:06 INFO - Running setup.py (path:/tmp/pip-654SAH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:42:06 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)) 15:42:06 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)) 15:42:06 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)) 15:42:06 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)) 15:42:06 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)) 15:42:06 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)) 15:42:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:42: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 15:42: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 15:42: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 15:42: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 15:42: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 15:42: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 15:42:08 INFO - Downloading blessings-1.5.1.tar.gz 15:42:08 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 15:42:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:42:08 INFO - Installing collected packages: blessings 15:42:08 INFO - Running setup.py install for blessings 15:42:08 INFO - Successfully installed blessings 15:42:08 INFO - Cleaning up... 15:42:08 INFO - Return code: 0 15:42:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:42:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:42:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:42:08 INFO - Reading from file tmpfile_stdout 15:42:08 INFO - Current package versions: 15:42:08 INFO - argparse == 1.2.1 15:42:08 INFO - blessings == 1.5.1 15:42:08 INFO - blobuploader == 1.2.4 15:42:08 INFO - docopt == 0.6.1 15:42:08 INFO - manifestparser == 1.1 15:42:08 INFO - mozInstall == 1.12 15:42:08 INFO - mozcrash == 0.16 15:42:08 INFO - mozdebug == 0.1 15:42:08 INFO - mozdevice == 0.47 15:42:08 INFO - mozfile == 1.2 15:42:08 INFO - mozhttpd == 0.7 15:42:08 INFO - mozinfo == 0.9 15:42:08 INFO - mozleak == 0.1 15:42:08 INFO - mozlog == 3.1 15:42:08 INFO - moznetwork == 0.27 15:42:08 INFO - mozprocess == 0.22 15:42:08 INFO - mozprofile == 0.27 15:42:08 INFO - mozrunner == 6.11 15:42:08 INFO - mozscreenshot == 0.1 15:42:08 INFO - mozsystemmonitor == 0.0 15:42:08 INFO - moztest == 0.7 15:42:08 INFO - mozversion == 1.4 15:42:08 INFO - psutil == 3.1.1 15:42:08 INFO - requests == 1.2.3 15:42:08 INFO - wsgiref == 0.1.2 15:42:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:42: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')]} 15:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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', '-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 0x7ffaa7c15c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, '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': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', '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 15:42:08 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 15:42:08 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 15:42:08 INFO - Using env: {'DISPLAY': ':0', 15:42:08 INFO - 'HOME': '/home/cltbld', 15:42:08 INFO - 'LANG': 'en_US.UTF-8', 15:42:08 INFO - 'LANGUAGE': 'en_US:en', 15:42:08 INFO - 'LOGNAME': 'cltbld', 15:42:08 INFO - 'MAIL': '/var/mail/cltbld', 15:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:08 INFO - 'MOZ_NO_REMOTE': '1', 15:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:08 INFO - 'NO_EM_RESTART': '1', 15:42:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:42:08 INFO - 'PWD': '/builds/slave/test-pgo', 15:42:08 INFO - 'SHELL': '/bin/bash', 15:42:08 INFO - 'SHLVL': '1', 15:42:08 INFO - 'TERM': 'linux', 15:42:08 INFO - 'TMOUT': '86400', 15:42:08 INFO - 'USER': 'cltbld', 15:42:08 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:08 INFO - '_': '/tools/buildbot/bin/python'} 15:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 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)) 15:42:08 INFO - Cleaning up... 15:42:08 INFO - Return code: 0 15:42:08 INFO - Running post-action listener: _resource_record_post_action 15:42:08 INFO - Running post-action listener: _start_resource_monitoring 15:42:08 INFO - Starting resource monitoring. 15:42:08 INFO - ##### 15:42:08 INFO - ##### Running install step. 15:42:08 INFO - ##### 15:42:08 INFO - Running pre-action listener: _resource_record_pre_action 15:42:08 INFO - Running main action method: install 15:42:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:42:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:42:08 INFO - Reading from file tmpfile_stdout 15:42:08 INFO - Detecting whether we're running mozinstall >=1.0... 15:42:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:42:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:42:09 INFO - Reading from file tmpfile_stdout 15:42:09 INFO - Output received: 15:42:09 INFO - Usage: mozinstall [options] installer 15:42:09 INFO - Options: 15:42:09 INFO - -h, --help show this help message and exit 15:42:09 INFO - -d DEST, --destination=DEST 15:42:09 INFO - Directory to install application into. [default: 15:42:09 INFO - "/builds/slave/test-pgo"] 15:42:09 INFO - --app=APP Application being installed. [default: firefox] 15:42:09 INFO - mkdir: /builds/slave/test-pgo/build/application 15:42:09 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'] 15:42:09 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 15:42:23 INFO - Reading from file tmpfile_stdout 15:42:23 INFO - Output received: 15:42:23 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:42:23 INFO - Running post-action listener: _resource_record_post_action 15:42:23 INFO - ##### 15:42:23 INFO - ##### Running run-tests step. 15:42:23 INFO - ##### 15:42:23 INFO - Running pre-action listener: _resource_record_pre_action 15:42:23 INFO - Running main action method: run_tests 15:42:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:42:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:42:23 INFO - Python 2.7.3 15:42:23 INFO - Return code: 0 15:42:23 INFO - grabbing minidump binary from tooltool 15:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:23 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 0x1fb1d70>, '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 0x1fb2b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb2fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:42:23 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 15:42:23 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 15:42:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:42:23 INFO - Return code: 0 15:42:23 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:42:23 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:42:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:42:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:42:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:42:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:42:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost 15:42:23 INFO - Using env: {'DISPLAY': ':0', 15:42:23 INFO - 'HOME': '/home/cltbld', 15:42:23 INFO - 'LANG': 'en_US.UTF-8', 15:42:23 INFO - 'LANGUAGE': 'en_US:en', 15:42:23 INFO - 'LOGNAME': 'cltbld', 15:42:23 INFO - 'MAIL': '/var/mail/cltbld', 15:42:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:42:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:42:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:23 INFO - 'MOZ_NO_REMOTE': '1', 15:42:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:42:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:23 INFO - 'NO_EM_RESTART': '1', 15:42:23 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:42:23 INFO - 'PWD': '/builds/slave/test-pgo', 15:42:23 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:42:23 INFO - 'SHELL': '/bin/bash', 15:42:23 INFO - 'SHLVL': '1', 15:42:23 INFO - 'TERM': 'linux', 15:42:23 INFO - 'TMOUT': '86400', 15:42:23 INFO - 'USER': 'cltbld', 15:42:23 INFO - 'XDG_SESSION_COOKIE': 'cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452', 15:42:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:23 INFO - '_': '/tools/buildbot/bin/python'} 15:42:23 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost'] with output_timeout 3600 15:42:23 INFO - mozversion INFO | application_buildid: 20151124143004 15:42:23 INFO - mozversion INFO | application_changeset: 8a3e98127ebc28fcc01d27bdedb4c959a456c47d 15:42:23 INFO - mozversion INFO | application_display_name: Nightly 15:42:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:42:23 INFO - mozversion INFO | application_name: Firefox 15:42:23 INFO - mozversion INFO | application_remotingname: firefox 15:42:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 15:42:23 INFO - mozversion INFO | application_vendor: Mozilla 15:42:23 INFO - mozversion INFO | application_version: 45.0a1 15:42:23 INFO - mozversion INFO | platform_buildid: 20151124143004 15:42:23 INFO - mozversion INFO | platform_changeset: 8a3e98127ebc28fcc01d27bdedb4c959a456c47d 15:42:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 15:42:23 INFO - mozversion INFO | platform_version: 45.0a1 15:42:23 INFO - 2015-11-24 15:42:23,760 DEBUG : using testdate: 1448408543 15:42:23 INFO - 2015-11-24 15:42:23,760 DEBUG : actual date: 1448408543 15:42:23 INFO - 2015-11-24 15:42:23,839 INFO : starting webserver on 'localhost:41153' 15:42:23 INFO - 2015-11-24 15:42:23,840 INFO : Starting test suite talos-linux64-ix-010.e 15:42:23 INFO - 2015-11-24 15:42:23,840 INFO : Starting test dromaeo_css 15:42:23 INFO - 2015-11-24 15:42:23,840 DEBUG : operating with platform_type : linux_ 15:42:23 INFO - 2015-11-24 15:42:23,841 INFO : Initialising browser for dromaeo_css test... 15:42:23 INFO - 2015-11-24 15:42:23,858 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNAwqHT/profile http://localhost:41153/getInfo.html 15:42:29 INFO - 2015-11-24 15:42:29,458 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:42:29 INFO - 2015-11-24 15:42:29,458 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:42:29 INFO - 2015-11-24 15:42:29,459 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:42:29 INFO - 2015-11-24 15:42:29,459 DEBUG : BROWSER_OUTPUT: __metrics 15:42:30 INFO - 2015-11-24 15:42:30,477 INFO : Browser initialized. 15:42:30 INFO - 2015-11-24 15:42:30,477 INFO : Running cycle 1/1 for dromaeo_css test... 15:42:30 INFO - 2015-11-24 15:42:30,478 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNAwqHT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:42:42 INFO - 2015-11-24 15:42:42,283 DEBUG : BROWSER_OUTPUT: RSS: Main: 141852672 15:42:42 INFO - 2015-11-24 15:42:42,283 DEBUG : BROWSER_OUTPUT: 15:43:31 INFO - 2015-11-24 15:43:31,900 DEBUG : BROWSER_OUTPUT: 1448408611896 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] 15:45:40 INFO - 2015-11-24 15:45:40,955 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41153/tests/dromaeo/cssquery-ext.html) 15:45:42 INFO - 2015-11-24 15:45:42,161 DEBUG : BROWSER_OUTPUT: RSS: Main: 147718144 15:45:42 INFO - 2015-11-24 15:45:42,161 DEBUG : BROWSER_OUTPUT: 15:48:44 INFO - 2015-11-24 15:48:44,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/dromaeo/cssquery-ext.html (next: http://localhost:41153/tests/dromaeo/cssquery-jquery.html) 15:48:44 INFO - 2015-11-24 15:48:44,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 142180352 15:48:44 INFO - 2015-11-24 15:48:44,600 DEBUG : BROWSER_OUTPUT: 15:51:46 INFO - 2015-11-24 15:51:46,304 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41153/tests/dromaeo/cssquery-mootools.html) 15:51:46 INFO - 2015-11-24 15:51:46,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 142872576 15:51:46 INFO - 2015-11-24 15:51:46,898 DEBUG : BROWSER_OUTPUT: 15:54:48 INFO - 2015-11-24 15:54:48,540 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41153/tests/dromaeo/cssquery-prototype.html) 15:54:49 INFO - 2015-11-24 15:54:49,147 DEBUG : BROWSER_OUTPUT: RSS: Main: 141373440 15:54:49 INFO - 2015-11-24 15:54:49,147 DEBUG : BROWSER_OUTPUT: 15:57:51 INFO - 2015-11-24 15:57:51,431 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41153/tests/dromaeo/cssquery-yui.html) 15:57:52 INFO - 2015-11-24 15:57:52,012 DEBUG : BROWSER_OUTPUT: RSS: Main: 141275136 15:57:52 INFO - 2015-11-24 15:57:52,012 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,411 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/dromaeo/cssquery-yui.html (next: http://localhost:41153/tests/dromaeo/cssquery-dojo.html) 16:00:54 INFO - 2015-11-24 16:00:54,669 DEBUG : BROWSER_OUTPUT: RSS: Main: 144297984 16:00:54 INFO - 2015-11-24 16:00:54,670 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,670 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:00:54 INFO - 2015-11-24 16:00:54,670 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:00:54 INFO - 2015-11-24 16:00:54,670 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:00:54 INFO - 2015-11-24 16:00:54,670 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:00:54 INFO - 2015-11-24 16:00:54,670 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3256.772025958184;3268.23196548811;3309.1230823831675;3309.238875058737;3316.3035762489876;5276.34045744282;5522.944770552293;5555.250041244431;5566.55467562595;5569.164625306211;5381.246625472427;5431.293931788872;5494.066008778507;5639.915401268984;5642.7178641067985;4984.77568509418;5039.067772462088;5082.212257100137;5114.181730923053;5115.616328775357;5042.420121685998;5085.771140298695;5124.385073791151;5144.511271429208;5217.37391513019;4875.171220892421;4953.157963146274;5020.347354843883;5077.466865979074;5133.435322114565;5039.47085556015;5040.243963405482;5048.066107770038;5073.949260507385;5101.617378696613;4930.753462326869;4991.575716064139;5001.274815151783;5013.824516141917;5018.7490625468945;5172.146595811687;5217.739113044333;5274.26160337553;5322.840314790524;5332.3601167859715;5980.13288073227;6005.39946005396;6008.8497787555225;6009.849753756147;6020.277566692025;6057.727402266909;6058.606190597597;6058.909116363258;6059.0608455689435;6061.393860613947;5365.543928766059;5477.698726570037;5540.944590554123;5616.9157462638095;5618.63478873876;5954.46409823118;5965.642061476326;6022.337542870281;6035.275766907999;6049.939500604937;5915.319738230072;5927.288725352985;5930.614510056833;5932.436418540232;5937.940620593825;5741.62679425836;5947.345791962881;5952.0476723724;5959.433853783884;6007.609505382224;5813.82558523245;5854.385289544622;5899.085641725539;6000.759969601167;6050.878982420327;5988.700564971734;5990.5507086968655;5992.730327135289;5994.790182343597;6034.2155519782145;4444.599986001275;4575.084983003413;4581.610563102107;4590.9540904591195;4628.583427491542;4037.475128233311;4040.7979601019833;4087.7751723655483;4107.6508496777515;4119.402686610425;5693.373728889819;5730.971345143247;5741.942580574224;5776.797812076557;5801.158831969341;5758;5893.174955506232;5949.553783466257;5958.553108516879;6019.277686677627;5775.740091695884;5825.76696932118;5842.036064049385;5842.2405087338375;5886.293644762571;5218.086834803894;5227.111391063529;5231.633785634969;5236.16221404581;5244.370675518886;5953.493953013955;6111.816645500642;6215.689215539295;6260;6283.151774510474;5988.071848863433;6039.275286965592;6046.879062418639;6060.848478788065;6073.665948372705;6124.8162555121635;6207;6208.627482351073;6269.059641053696;6270.623762574261;5763.884722305615;5825.446582574648;5878.147668588116;5889.440503152194;5987.880242395215;5982.46157845796;6007.669578173242;6022.307434645072;6052.485538729081;6071.423214794588;5902.321233058082;5905.261842269716;5914.556408269483;5934.6142500737315;5935.851603709769;5643.689597072201;5751.798687045934;5774.538036956949;5897.144913987362;5922.7927022554;5784.5083167929515;5798.188253644409;5853.0635098384055;5855.560832937462;5880.206172166702;5737.454941780524;5924.0521516558865;5965.6122352048715;5974.2830860297045;6008.849778755566;5551.916721249104;5669.574781891292;5759.769609215585;5773.942260577341;5833.387494313036 16:00:54 INFO - 2015-11-24 16:00:54,671 DEBUG : BROWSER_OUTPUT: |1;ext.html;556.510270961554;578.5718568259489;604.8487878030493;613.7483631710998;625.5839866488782;39599;39937.60062399375;40199.39700904481;40353.38586456542;40425;42631.78684106583;43310;43358.349624755574;43425;43428.78285608567;8603.311735061212;8745.693900713477;8785.121487851227;8789.824203515958;8798.42810217337;8545.914540854605;8711.825763484727;8755.474671519698;8782.868256976151;8786.121387861194;8737.606807693637;8830.911690883106;8835.204831565157;8840.734777956639;8846.20384165425;8811.823763524693;8815.867761983576;8837.911620883773;8853.380263381567;8906.51014194219;8624.051354351017;8641.092685268019;8665.220130188314;8725.738227853175;8753.606087726068;41335.7933210335;41471.37792933109;41706;41891;42140.367894481606;3098.597182366278;3205.37495188436;3247.1395080303737;3309.973908088476;3571.624979377154;30289.394212115854;30711.69288307133;30769.538456923165;31227.687723122704;31332.843335783175;3765.2093060457064;3774.924501509982;3837.3860182370686;3853.9229215415535;3952.367621180626;261914;262058;262477;263025;263507.6824615884;56662.433375665714;58194.70902645544;58472.41527584755;58481.41518584845;58559.70720146372;53674;61076;61958;62196.37803621906;62527.37472625307;15101.244937753068;15225.086494810346;15341.309641066178;15377;15493.302801373966;11189.776204475866;11368.261063030877;11403.201775875776;11437.856214378495;11504.884951150549;19694.606107878048;19924;19955;20011.099500522512;20108.89945550263;7993.560354180576;8173.877391839127;8254.504729716236;8272.669093236324;8305.169483051768;932.305432452827;974.5663179884883;995.5470261031213;1008.2589296866797;1098.566066403782;28729.138125856258;28816.279593010342;28948.13155605335;28966;29298.267543311587;1068.5458680060942;1154.982675259855;1179.4161889864572;1198.874118217592;1209.5524655877205;14810.54144514671;14811.407543698348;14859.108453492609;14903.088148255016;14960.326785294688;320243;325323;326037;326061;326340;53649.46350536523;53943;53978.46021539813;54038;54041;1367.8153449284218;1367.9179249244878;1376;1377.8415482219473;1383.619504636213;27728.168154955383;28149.859250704434;28261.858690706416;28558.42883142367;28738.281542961595;46710.299345510524;46999.53000469952;47324.290135648684;47410.52589474199;47427.288590670476;295371;295596;295650;296388;297473;31040.379192416476;31900.202494937814;32022;32143.35713285768;32471.350572987936;38404;38769.418458722575;38908.221835563694;39330.41004384994;39451.2109757809;34243.82878085594;36297.81851090727;36491.81754091319;36523.63476365202;36867.07832304214;35975.460368095024;36125.27749444944;36360.454593181654;36444.45333319949;36635.63364366429;35550.26722194132;35699.06149237088;35995;36094.4585831218;36184.27631447409;31602.525962111045;32069.198270043435;33272.500912486816;33343.49984750279;33377.83311083429;25138.62292065584;25362.74637253604;25364;25438.76750196214;25481 16:00:54 INFO - 2015-11-24 16:00:54,671 DEBUG : BROWSER_OUTPUT: |2;jquery.html;348.9585922354228;359.4051844197852;363.99272014559705;365.0690738616527;367.1958411079737;16699.24853381598;17334.046627435488;17670.116494175272;17922.462326130222;18333.266669333243;11057.170712196577;12206.450709718061;16036.797240207003;16051.438199663015;16094.59763505913;4374.693771436001;5424.918626220609;5438.646487978289;5439.809606663767;5448.700321482317;5389.218563308337;5457.617966742329;5465.480779325958;5466;5468.507834294913;4954.777035033432;5011.098002359574;5084.288199652052;5221.2951251580935;5337.626366154371;5285.947140528584;5307.52232299095;5310.62825602208;5318.707471089088;5345.465453454663;5079.390473143247;5217.373915130171;5234.42421333653;5249.317588713443;5253.212018197301;12525.74948501034;12718.491260349574;15762;20630.690539641917;20633.793662063337;2017.1729590867674;2033.379819155157;2051.138521820839;2069.513664288906;2160.2115227941845;9630.903690963141;9725.708228753077;9752.073553012453;9787.657431989917;11559.959603635629;1770.5750619851142;1837.012511032553;1845.8707890447674;1853.13829069482;1856.2574970011967;59568;62186.37813621851;63466;63733;63805.680971595306;10059.949700251453;10132.645357412603;10309.639162629272;10396.844047339297;10444.21668374875;13485.662858428617;13558.864411355957;13575.864241357658;13586.592402227949;13652.453901843815;7363.521371110861;7501.699932002659;7525.397968162534;7569.129550101698;7639.159692433828;7198.676059577331;7255.165655949528;7277.126744790553;7299.59852208123;7301.853962920712;4432.800523976429;4477.977610111929;4482.125985432809;4492.932606010912;4564.109998550251;1823.471193353939;1823.972640410395;1856.7957524672274;1869.4952362861952;1888.4145914766466;897.4077109108088;952.5570609666538;960.2270172511056;986.7379255740358;988.1650005745134;16133.193340332937;16310.836891631168;16578.336866525446;16703;17316.826831731774;869.7303835810919;880.4937161132375;891.2647066170435;917.312015988009;920.963161473547;9378.577963991636;9434.48110353937;9545.52272386378;9784.412935223909;10035.247356448295;484368;488935;490840;493190;517419;79038.60480697559;82499.17500825036;83839;84034.5798271017;84087.59124087665;1120.2158489057692;1131.264677959333;1144.09616403044;1153.3022521374448;1153.9942300288444;14858.257087145383;15516.22418879074;15564.299606517507;15572.299246533707;15870.2064896757;31869;32447;33282.16794580155;33732.3253534923;33974.830125849214;41466.170676585694;41478.58521414868;42236.36645450382;42421.36367954545;46277.30584041309;9376.437413755197;9562.474063926273;9578.23374130054;10133.543990520297;10181.694549163536;17653.382131625844;17851;17884.10579471047;18212.36256731008;18399.080045997915;13428.664283392602;14040.1575905446;14091.929540352232;15154.469593564614;15185.468508602145;13902;14369.928150359181;14846.554603361916;15036.924815375853;15078.246087695352;15388.307526161574;15518.612034699223;15612.375504979673;15815.288312025756;15822.367105315658;9862.654807081717;9908.157806586521;9912.256580756617;9942.552585133832;10065;10468.63359782404;12805.423755931191;14591;14600.12399256048;15294.77057844111 16:00:54 INFO - 2015-11-24 16:00:54,671 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1506.0888162661583;1654.0985163086118;1721.956951076223;1732.4023211991866;1772.849307808837;6346.174997250351;6469.644169570672;7462.813429664255;7834.999999999993;7850.175731548191;7228.457865660083;7405.5556666599905;7465.626718664059;7469.215732348093;7477.663505142268;2167.783221677835;2223.6442169252923;2227.27613525604;2229.8216142708543;2234.318532847481;2240.73111226653;2263.5246598214394;2293.1859189987563;2304.2626359564947;2306.8269879759;2233.3746550965675;2252.7521972583004;2270.6707527408516;2274.80664143548;2294.059435631383;2224.744154422246;2229.2977712020825;2266.150193677371;2267.331137314496;2267.8072363849174;2240.1263507232193;2243.4279258789065;2253.794220092243;2263.1626298269744;2264.388615073937;7570.280823321831;7666.769996900102;7728.6522106505345;7729.072511298624;7763.805904852367;4604.746738929358;4652.999605084923;4657.371254880615;4667.253239481701;4678.830292426894;2982.4631566318053;3032.7725420593324;3121.562981182636;3133.5143052826847;3159.41550813098;1042.1766804224653;1054.3463052907246;1756.1482680899753;1807.9909600451913;1818.4999125240533;10203.336783109075;10321.329113607591;10361.585536578528;10361.844572331422;10369.844452333222;4026.355783074694;4089.997950502146;4101.364288535282;4161.8335266589;4171.395147703566;8547.700830470903;8590.441621294596;8594.527300998505;8613.784655383666;8619.1811777881;4567.931481027787;4568.703034302826;4588.2888152336445;4591.9081618367445;4601.700889442175;4212.852550160791;4228.9788551057045;4243.27864263076;4384.583464570861;4391.912161756747;3615.728820338485;3617.5478065241846;3640.7087433005295;3660.853565857336;3665.065408320861;1109.8890110988998;1132.943352832355;1136.971575710614;1137.056243318044;1148.1847887999613;1038.896110388955;1040.7502199472224;1044.9216308776872;1175.3887978251432;1318.9340532973313;6714.227863795629;6769.695363708645;6789;6818.795436136924;6834.8633027340165;491.9262110683355;493.247797109411;496.99006019880125;501.00350104832216;503.71540079854765;5442.401335853053;5455.754491047913;5500.42245564218;5502.972485137549;5519.862003449946;8817.515036673045;8877.402067627889;8995.640174393082;9084.227840633488;9139.314551408672;8997.505137217386;9015.864762028576;9039.593218305192;9055.501947392957;9066.682666106786;6548.476121910331;6698.196216454154;6721.462283017347;6940.548864323803;6953.89569156463;6975.895361569479;6989.615571143636;6991.335823096798;7013.964930175316;7014.123234595676;7848.2151784823345;7978.680852765824;8028.83942321162;8055.7583272501915;8157.8368432632205;8179.550124743197;8255.545944973084;8285.875711864448;8341.24928756415;8378.58107094655;5747.281589801275;5889.146073819358;6701.195856497252;6721.462283017445;6833.350831670983;7080.327368899946;7122.786316410516;7164.820879478055;7197.712091516491;7208.639568021683;6601.801945941629;6631.701573429111;6668.766593169215;6815.693293801692;6928.099347084847;6573.57271777333;6649.235337936199;6711.228208756055;6758.695858686469;6796.2524122347495;6615.04081908111;6768.2216545097945;6809.455243580601;6814.62519561409;6822.624755638289;5893.675847828239;5918.704064796829;5935.940640593539;5953.761849526144;5965.612235204698;6025.427584379477;6029.095635654687;6079.240094988127;6080.969595151996;6082.878342433215 16:00:54 INFO - 2015-11-24 16:00:54,674 DEBUG : BROWSER_OUTPUT: |4;prototype.html;336.86357025404715;339.3772427595362;344.78278684428807;347.42154313068744;347.5360393874177;6279.937200627993;6292.244930608334;6378.362163783625;6399.328070552595;6466;6247.5001999840015;6258.155149054876;6298.968505157468;6304.71628776705;6308.243010838706;4765.475797662263;4780.784864681098;4781.115493633689;4784.736839473828;4792.784324705379;4784.306275590037;4789.401324834395;4792.856214313576;4796.952030479685;4799.088173247089;4653.6044436222965;4758.048390321932;4763.49983251757;4768.952310476886;4773.57037866592;4736.242201247801;4754.429468463789;4762.571368576845;4765.833195838164;4769.070031343889;4083.591640835922;4114;4116;4116.259073366793;4120.608542188487;6021.42796434338;6022.759089636409;6027.547933904931;6029.125776762388;6044.758209671608;5328.733563321818;5505.366882808448;5505.788726480168;5520.475554822286;5528.9723551381985;6666.999950007489;6674.766383176566;6675.332466753334;6684.431823294977;6687.331266873322;4541.795619197111;4548.204064288736;4552.590266875964;4556.453225612914;4561.475430325489;6732.124823772927;6788.456923446161;6875.3812156905615;6879.589684114744;6886.793396198122;4580.335851301543;4585.266357382803;4585.403897493305;4601.861944141681;4622.745748983771;4667.346571479996;4668.416447944007;4688.367070445514;4689.929651055237;4696.882577935579;5490.588205884575;5491.945080549223;5495.2856128703015;5498.092814685532;5499.395066542677;6525.282218955911;6707.49693772969;6711.161104861892;6746.966265168643;6756.3919247266995;1628.8126865410393;1654.3713388912136;1663.035439445119;1666.0753281928646;1666.433412639684;1079.3092420850662;1081.8431327457631;1084.7450849050467;1107.8227483602584;1112.9833052504218;6447.451966582893;6463.353664633593;6464.515161362822;6482.254540727877;6502.869942601216;6806.4554835613035;6810.489213309022;6823.624700641512;6823.761168359084;6823.931760682429;6444.871102578016;6467.126937863422;6485.805425837232;6487.513436492282;6490.902636460457;3729.4032954727113;3768;3787.034306251888;3799.962000379961;3809.447630093621;7304.598247096461;7321.450891183076;7340.229275925951;7357.117145942414;7360.926390736131;7004.229534751174;7130.465215108888;7153.642317884085;7175.712971481186;7180.497365184491;7263.273672632695;7311.415086793044;7315.743948961761;7321.231270716498;7341.70633174667;6945.548539344926;6949.791506254821;6950.895736564057;6957.339052789976;6966.756163534252;6653.800385988428;6766.661666916733;6814.693338799667;6855.348741869514;6857.485688573477;7283.7450689225625;7314;7321.267873212849;7326.120865496175;7338.339549440576;4611.2852507861335;4627.652926030494;4641.234196357564;4645.3496510487885;4645.350900430433;6805.319468053155;6928.237893831775;6928.341807528277;6930;6941.09765730472;6714.160729908762;6754.797356079325;6775.119234499483;6777.661116944231;6783.287754785676;6750.8649827004165;6763.154605674291;6768.2216545097945;6773.491988101011;6774.661266936535;6378;6575.638339891352;6580.210374754868;6586.50601204902;6640.605472850755;6827.590344579341;6833.624150671761;6836.658167091526;6843.076184715;6846.110005699227;6511.869762604627;6531.934680653133;6538.509611779041;6582.17722784652;6584.209894812461 16:00:54 INFO - 2015-11-24 16:00:54,675 DEBUG : BROWSER_OUTPUT: |5;yui.html;714.928507149285;788.0622059748902;790.1720487819418;816.0900595835643;832.3299743706314;12695.555655552045;12937.029722770807;12945.352732363392;12957.09300348976;12958.80561791574;12896.355182240897;12977.416016279267;13002.479900803957;13114.016448766333;13119.803202951964;811.123986095019;836.5315423362936;837.3970741066422;837.4975014990987;837.6481877611418;687.6149356360429;698.4726531468734;811.2212276214841;828.2545708862042;835.7116794705817;857.0486759696731;863.1196179896501;864.4726716702806;867.0679020053436;867.4621734524573;840.3781201910604;849.6941101203562;852.0457088061349;857.0829212742368;865.6364326982651;634.5019159959456;634.784173381048;635.3170341882495;636.9585976911486;640.9551331406803;13575.049746517747;13632.113912595749;13696.109752866032;13700.3834827433;13707.588772336845;11502.942485287604;12306.81539776904;12578.937105314506;12704.80942785859;12722.427490762939;9450.85823712645;10005.79988400228;10356.119729822898;10556.099902017633;10677.41274229917;3501.9824900875587;3571.499990001402;3591;3636.054625797285;3680.5215322008235;50158.7492062541;50370.7481462594;50449.24326135111;50498.74750626223;50589.494105058846;9605.47169905648;9676.419414835133;9818;9842.114209721016;9997.200223982063;18253.26986920535;18272;18492.537686557942;18528.907355463136;18538;7282.890756638654;7283.271672832674;7366.373858222111;7472.402207823361;7547.886781698279;4230.280852255062;4248.511421186541;4311.180875633682;4338.197433474754;4348.326009468537;3043.6956304369387;3055.282008727947;3065.693430656961;3087.8919237826567;3090.196548897303;505.9392872855207;521.2467983763426;529.5895680847298;536.243896106488;541.1314839682332;498.076068892207;561.9659825920327;612.5240351259724;642.6401215319436;668.7859884836805;8439.746807595782;8637.697680581276;8650.480971141626;8682.86975695365;8801.779955501164;532.2575007864008;561.4974597735086;649.0556240669814;660.5013215022627;669.5079116849112;3614.9277014459562;4028.093678922278;4050.311447054008;4115.279826030433;4154.750714957052;57845;57847;58034;58680.41319586835;59156.704216479506;51344.74327628338;51787.74106129445;51825.740871295406;51833.7408312956;52221;45944.3108353375;47029.5297047032;47084.764576177586;47279;47474.5252547477;8117.066537348044;8132.064812546633;8187.672493100447;8218.096009439076;8301.958490207511;33825.830870845486;35052.29895402129;35193.120171995906;35676;36082.63917360793;38900.221995560496;40661.59338406578;44433.77783111064;44547.777261113486;44673.55326446694;9627.951860240653;9667.419954802734;9780.119789219005;9877;9982.301238913205;10576;10720.410377429316;10799.298045627009;10801.513931872927;10838.187135964992;9915.65295214664;10249.33379330341;10403.895961040293;10477.974138790054;10534.736631583964;10533.262671612914;10539.841902371318;10673.141858340457;10760.300580462557;10882.183836212474;9822.165115965223;10026.097651211427;10046.899531004889;10166.542505587118;10166.898331016891;8718.17177368149;8747.398381656609;8796.428232164888;8899.15458031486;9071.591778369857;8115.513069215866;8424.915750842412;8596.39825212254;8754.649814007369;8865.734027979172 16:00:54 INFO - 2015-11-24 16:00:54,675 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:00:54 INFO - 2015-11-24 16:00:54,675 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:00:54 INFO - 2015-11-24 16:00:54,675 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,304 16:00:54 INFO - 2015-11-24 16:00:54,675 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:00:54 INFO - 2015-11-24 16:00:54,676 DEBUG : BROWSER_OUTPUT: __startTimestamp1448409654663__endTimestamp 16:00:54 INFO - 2015-11-24 16:00:54,676 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,676 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:00:54 INFO - 2015-11-24 16:00:54,676 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:00:54 INFO - 2015-11-24 16:00:54,676 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,677 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5547.76 Median:5798.19 stddev:634.68 (10.9%) stddev-sans-first:610.65 16:00:54 INFO - 2015-11-24 16:00:54,678 DEBUG : BROWSER_OUTPUT: Values: 3256.8 3268.2 3309.1 3309.2 3316.3 5276.3 5522.9 5555.3 5566.6 5569.2 5381.2 5431.3 5494.1 5639.9 5642.7 4984.8 5039.1 5082.2 5114.2 5115.6 5042.4 5085.8 5124.4 5144.5 5217.4 4875.2 4953.2 5020.3 5077.5 5133.4 5039.5 5040.2 5048.1 5073.9 5101.6 4930.8 4991.6 5001.3 5013.8 5018.7 5172.1 5217.7 5274.3 5322.8 5332.4 5980.1 6005.4 6008.8 6009.8 6020.3 6057.7 6058.6 6058.9 6059.1 6061.4 5365.5 5477.7 5540.9 5616.9 5618.6 5954.5 5965.6 6022.3 6035.3 6049.9 5915.3 5927.3 5930.6 5932.4 5937.9 5741.6 5947.3 5952.0 5959.4 6007.6 5813.8 5854.4 5899.1 6000.8 6050.9 5988.7 5990.6 5992.7 5994.8 6034.2 4444.6 4575.1 4581.6 4591.0 4628.6 4037.5 4040.8 4087.8 4107.7 4119.4 5693.4 5731.0 5741.9 5776.8 5801.2 5758.0 5893.2 5949.6 5958.6 6019.3 5775.7 5825.8 5842.0 5842.2 5886.3 5218.1 5227.1 5231.6 5236.2 5244.4 5953.5 6111.8 6215.7 6260.0 6283.2 5988.1 6039.3 6046.9 6060.8 6073.7 6124.8 6207.0 6208.6 6269.1 6270.6 5763.9 5825.4 5878.1 5889.4 5987.9 5982.5 6007.7 6022.3 6052.5 6071.4 5902.3 5905.3 5914.6 5934.6 5935.9 5643.7 5751.8 5774.5 5897.1 5922.8 5784.5 5798.2 5853.1 5855.6 5880.2 5737.5 5924.1 5965.6 5974.3 6008.8 5551.9 5669.6 5759.8 5773.9 5833.4 16:00:54 INFO - 2015-11-24 16:00:54,678 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,678 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46615.03 Median:36329.14 stddev:77223.63 (212.6%) stddev-sans-first:77362.83 16:00:54 INFO - 2015-11-24 16:00:54,679 DEBUG : BROWSER_OUTPUT: Values: 556.5 578.6 604.8 613.7 625.6 39599.0 39937.6 40199.4 40353.4 40425.0 42631.8 43310.0 43358.3 43425.0 43428.8 8603.3 8745.7 8785.1 8789.8 8798.4 8545.9 8711.8 8755.5 8782.9 8786.1 8737.6 8830.9 8835.2 8840.7 8846.2 8811.8 8815.9 8837.9 8853.4 8906.5 8624.1 8641.1 8665.2 8725.7 8753.6 41335.8 41471.4 41706.0 41891.0 42140.4 3098.6 3205.4 3247.1 3310.0 3571.6 30289.4 30711.7 30769.5 31227.7 31332.8 3765.2 3774.9 3837.4 3853.9 3952.4 261914.0 262058.0 262477.0 263025.0 263507.7 56662.4 58194.7 58472.4 58481.4 58559.7 53674.0 61076.0 61958.0 62196.4 62527.4 15101.2 15225.1 15341.3 15377.0 15493.3 11189.8 11368.3 11403.2 11437.9 11504.9 19694.6 19924.0 19955.0 20011.1 20108.9 7993.6 8173.9 8254.5 8272.7 8305.2 932.3 974.6 995.5 1008.3 1098.6 28729.1 28816.3 28948.1 28966.0 29298.3 1068.5 1155.0 1179.4 1198.9 1209.6 14810.5 14811.4 14859.1 14903.1 14960.3 320243.0 325323.0 326037.0 326061.0 326340.0 53649.5 53943.0 53978.5 54038.0 54041.0 1367.8 1367.9 1376.0 1377.8 1383.6 27728.2 28149.9 28261.9 28558.4 28738.3 46710.3 46999.5 47324.3 47410.5 47427.3 295371.0 295596.0 295650.0 296388.0 297473.0 31040.4 31900.2 32022.0 32143.4 32471.4 38404.0 38769.4 38908.2 39330.4 39451.2 34243.8 36297.8 36491.8 36523.6 36867.1 35975.5 36125.3 36360.5 36444.5 36635.6 35550.3 35699.1 35995.0 36094.5 36184.3 31602.5 32069.2 33272.5 33343.5 33377.8 25138.6 25362.7 25364.0 25438.8 25481.0 16:00:54 INFO - 2015-11-24 16:00:54,680 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,680 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27517.78 Median:354.18 stddev:81018.68 (22874.9%) stddev-sans-first:81220.28 16:00:54 INFO - 2015-11-24 16:00:54,681 DEBUG : BROWSER_OUTPUT: Values: 349.0 359.4 364.0 365.1 367.2 16699.2 17334.0 17670.1 17922.5 18333.3 11057.2 12206.5 16036.8 16051.4 16094.6 4374.7 5424.9 5438.6 5439.8 5448.7 5389.2 5457.6 5465.5 5466.0 5468.5 4954.8 5011.1 5084.3 5221.3 5337.6 5285.9 5307.5 5310.6 5318.7 5345.5 5079.4 5217.4 5234.4 5249.3 5253.2 12525.7 12718.5 15762.0 20630.7 20633.8 2017.2 2033.4 2051.1 2069.5 2160.2 9630.9 9725.7 9752.1 9787.7 11560.0 1770.6 1837.0 1845.9 1853.1 1856.3 59568.0 62186.4 63466.0 63733.0 63805.7 10059.9 10132.6 10309.6 10396.8 10444.2 13485.7 13558.9 13575.9 13586.6 13652.5 7363.5 7501.7 7525.4 7569.1 7639.2 7198.7 7255.2 7277.1 7299.6 7301.9 4432.8 4478.0 4482.1 4492.9 4564.1 1823.5 1824.0 1856.8 1869.5 1888.4 897.4 952.6 960.2 986.7 988.2 16133.2 16310.8 16578.3 16703.0 17316.8 869.7 880.5 891.3 917.3 921.0 9378.6 9434.5 9545.5 9784.4 10035.2 484368.0 488935.0 490840.0 493190.0 517419.0 79038.6 82499.2 83839.0 84034.6 84087.6 1120.2 1131.3 1144.1 1153.3 1154.0 14858.3 15516.2 15564.3 15572.3 15870.2 31869.0 32447.0 33282.2 33732.3 33974.8 41466.2 41478.6 42236.4 42421.4 46277.3 9376.4 9562.5 9578.2 10133.5 10181.7 17653.4 17851.0 17884.1 18212.4 18399.1 13428.7 14040.2 14091.9 15154.5 15185.5 13902.0 14369.9 14846.6 15036.9 15078.2 15388.3 15518.6 15612.4 15815.3 15822.4 9862.7 9908.2 9912.3 9942.6 10065.0 10468.6 12805.4 14591.0 14600.1 15294.8 16:00:54 INFO - 2015-11-24 16:00:54,681 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,681 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5245.78 Median:5818.21 stddev:2676.03 (46.0%) stddev-sans-first:2668.77 16:00:54 INFO - 2015-11-24 16:00:54,682 DEBUG : BROWSER_OUTPUT: Values: 1506.1 1654.1 1722.0 1732.4 1772.8 6346.2 6469.6 7462.8 7835.0 7850.2 7228.5 7405.6 7465.6 7469.2 7477.7 2167.8 2223.6 2227.3 2229.8 2234.3 2240.7 2263.5 2293.2 2304.3 2306.8 2233.4 2252.8 2270.7 2274.8 2294.1 2224.7 2229.3 2266.2 2267.3 2267.8 2240.1 2243.4 2253.8 2263.2 2264.4 7570.3 7666.8 7728.7 7729.1 7763.8 4604.7 4653.0 4657.4 4667.3 4678.8 2982.5 3032.8 3121.6 3133.5 3159.4 1042.2 1054.3 1756.1 1808.0 1818.5 10203.3 10321.3 10361.6 10361.8 10369.8 4026.4 4090.0 4101.4 4161.8 4171.4 8547.7 8590.4 8594.5 8613.8 8619.2 4567.9 4568.7 4588.3 4591.9 4601.7 4212.9 4229.0 4243.3 4384.6 4391.9 3615.7 3617.5 3640.7 3660.9 3665.1 1109.9 1132.9 1137.0 1137.1 1148.2 1038.9 1040.8 1044.9 1175.4 1318.9 6714.2 6769.7 6789.0 6818.8 6834.9 491.9 493.2 497.0 501.0 503.7 5442.4 5455.8 5500.4 5503.0 5519.9 8817.5 8877.4 8995.6 9084.2 9139.3 8997.5 9015.9 9039.6 9055.5 9066.7 6548.5 6698.2 6721.5 6940.5 6953.9 6975.9 6989.6 6991.3 7014.0 7014.1 7848.2 7978.7 8028.8 8055.8 8157.8 8179.6 8255.5 8285.9 8341.2 8378.6 5747.3 5889.1 6701.2 6721.5 6833.4 7080.3 7122.8 7164.8 7197.7 7208.6 6601.8 6631.7 6668.8 6815.7 6928.1 6573.6 6649.2 6711.2 6758.7 6796.3 6615.0 6768.2 6809.5 6814.6 6822.6 5893.7 5918.7 5935.9 5953.8 5965.6 6025.4 6029.1 6079.2 6081.0 6082.9 16:00:54 INFO - 2015-11-24 16:00:54,682 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,683 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5592.85 Median:6446.16 stddev:1721.56 (26.7%) stddev-sans-first:1680.59 16:00:54 INFO - 2015-11-24 16:00:54,683 DEBUG : BROWSER_OUTPUT: Values: 336.9 339.4 344.8 347.4 347.5 6279.9 6292.2 6378.4 6399.3 6466.0 6247.5 6258.2 6299.0 6304.7 6308.2 4765.5 4780.8 4781.1 4784.7 4792.8 4784.3 4789.4 4792.9 4797.0 4799.1 4653.6 4758.0 4763.5 4769.0 4773.6 4736.2 4754.4 4762.6 4765.8 4769.1 4083.6 4114.0 4116.0 4116.3 4120.6 6021.4 6022.8 6027.5 6029.1 6044.8 5328.7 5505.4 5505.8 5520.5 5529.0 6667.0 6674.8 6675.3 6684.4 6687.3 4541.8 4548.2 4552.6 4556.5 4561.5 6732.1 6788.5 6875.4 6879.6 6886.8 4580.3 4585.3 4585.4 4601.9 4622.7 4667.3 4668.4 4688.4 4689.9 4696.9 5490.6 5491.9 5495.3 5498.1 5499.4 6525.3 6707.5 6711.2 6747.0 6756.4 1628.8 1654.4 1663.0 1666.1 1666.4 1079.3 1081.8 1084.7 1107.8 1113.0 6447.5 6463.4 6464.5 6482.3 6502.9 6806.5 6810.5 6823.6 6823.8 6823.9 6444.9 6467.1 6485.8 6487.5 6490.9 3729.4 3768.0 3787.0 3800.0 3809.4 7304.6 7321.5 7340.2 7357.1 7360.9 7004.2 7130.5 7153.6 7175.7 7180.5 7263.3 7311.4 7315.7 7321.2 7341.7 6945.5 6949.8 6950.9 6957.3 6966.8 6653.8 6766.7 6814.7 6855.3 6857.5 7283.7 7314.0 7321.3 7326.1 7338.3 4611.3 4627.7 4641.2 4645.3 4645.4 6805.3 6928.2 6928.3 6930.0 6941.1 6714.2 6754.8 6775.1 6777.7 6783.3 6750.9 6763.2 6768.2 6773.5 6774.7 6378.0 6575.6 6580.2 6586.5 6640.6 6827.6 6833.6 6836.7 6843.1 6846.1 6511.9 6531.9 6538.5 6582.2 6584.2 16:00:54 INFO - 2015-11-24 16:00:54,683 DEBUG : BROWSER_OUTPUT: 16:00:54 INFO - 2015-11-24 16:00:54,683 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13619.27 Median:51806.74 stddev:16180.65 (31.2%) stddev-sans-first:16197.02 16:00:54 INFO - 2015-11-24 16:00:54,684 DEBUG : BROWSER_OUTPUT: Values: 714.9 788.1 790.2 816.1 832.3 12695.6 12937.0 12945.4 12957.1 12958.8 12896.4 12977.4 13002.5 13114.0 13119.8 811.1 836.5 837.4 837.5 837.6 687.6 698.5 811.2 828.3 835.7 857.0 863.1 864.5 867.1 867.5 840.4 849.7 852.0 857.1 865.6 634.5 634.8 635.3 637.0 641.0 13575.0 13632.1 13696.1 13700.4 13707.6 11502.9 12306.8 12578.9 12704.8 12722.4 9450.9 10005.8 10356.1 10556.1 10677.4 3502.0 3571.5 3591.0 3636.1 3680.5 50158.7 50370.7 50449.2 50498.7 50589.5 9605.5 9676.4 9818.0 9842.1 9997.2 18253.3 18272.0 18492.5 18528.9 18538.0 7282.9 7283.3 7366.4 7472.4 7547.9 4230.3 4248.5 4311.2 4338.2 4348.3 3043.7 3055.3 3065.7 3087.9 3090.2 505.9 521.2 529.6 536.2 541.1 498.1 562.0 612.5 642.6 668.8 8439.7 8637.7 8650.5 8682.9 8801.8 532.3 561.5 649.1 660.5 669.5 3614.9 4028.1 4050.3 4115.3 4154.8 57845.0 57847.0 58034.0 58680.4 59156.7 51344.7 51787.7 51825.7 51833.7 52221.0 45944.3 47029.5 47084.8 47279.0 47474.5 8117.1 8132.1 8187.7 8218.1 8302.0 33825.8 35052.3 35193.1 35676.0 36082.6 38900.2 40661.6 44433.8 44547.8 44673.6 9628.0 9667.4 9780.1 9877.0 9982.3 10576.0 10720.4 10799.3 10801.5 10838.2 9915.7 10249.3 10403.9 10478.0 10534.7 10533.3 10539.8 10673.1 10760.3 10882.2 9822.2 10026.1 10046.9 10166.5 10166.9 8718.2 8747.4 8796.4 8899.2 9071.6 8115.5 8424.9 8596.4 8754.6 8865.7 16:00:54 INFO - 2015-11-24 16:00:54,684 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:00:54 INFO - 2015-11-24 16:00:54,684 DEBUG : BROWSER_OUTPUT: 16:00:55 INFO - 2015-11-24 16:00:55,321 INFO : Browser exited with error code: 0 16:00:55 INFO - 2015-11-24 16:00:55,342 INFO : Completed test dromaeo_css (00:18:31) 16:00:55 INFO - 2015-11-24 16:00:55,342 INFO : Starting test kraken 16:00:55 INFO - 2015-11-24 16:00:55,342 DEBUG : operating with platform_type : linux_ 16:00:55 INFO - 2015-11-24 16:00:55,343 INFO : Initialising browser for kraken test... 16:00:55 INFO - 2015-11-24 16:00:55,349 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkEpuw7/profile http://localhost:41153/getInfo.html 16:00:59 INFO - 2015-11-24 16:00:59,681 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:00:59 INFO - 2015-11-24 16:00:59,681 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:00:59 INFO - 2015-11-24 16:00:59,681 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:00:59 INFO - 2015-11-24 16:00:59,681 DEBUG : BROWSER_OUTPUT: __metrics 16:01:00 INFO - 2015-11-24 16:01:00,769 INFO : Browser initialized. 16:01:00 INFO - 2015-11-24 16:01:00,769 INFO : Running cycle 1/1 for kraken test... 16:01:00 INFO - 2015-11-24 16:01:00,769 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkEpuw7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:01:12 INFO - 2015-11-24 16:01:12,508 DEBUG : BROWSER_OUTPUT: RSS: Main: 142442496 16:01:12 INFO - 2015-11-24 16:01:12,509 DEBUG : BROWSER_OUTPUT: 16:01:15 INFO - 2015-11-24 16:01:15,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41153/tests/kraken/driver.html?testName=audio-beat-detection) 16:01:15 INFO - 2015-11-24 16:01:15,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 142340096 16:01:15 INFO - 2015-11-24 16:01:15,606 DEBUG : BROWSER_OUTPUT: 16:01:18 INFO - 2015-11-24 16:01:18,289 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41153/tests/kraken/driver.html?testName=audio-dft) 16:01:18 INFO - 2015-11-24 16:01:18,894 DEBUG : BROWSER_OUTPUT: RSS: Main: 142344192 16:01:18 INFO - 2015-11-24 16:01:18,894 DEBUG : BROWSER_OUTPUT: 16:01:21 INFO - 2015-11-24 16:01:21,737 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41153/tests/kraken/driver.html?testName=audio-fft) 16:01:22 INFO - 2015-11-24 16:01:22,304 DEBUG : BROWSER_OUTPUT: RSS: Main: 138502144 16:01:22 INFO - 2015-11-24 16:01:22,304 DEBUG : BROWSER_OUTPUT: 16:01:24 INFO - 2015-11-24 16:01:24,261 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41153/tests/kraken/driver.html?testName=audio-oscillator) 16:01:24 INFO - 2015-11-24 16:01:24,874 DEBUG : BROWSER_OUTPUT: RSS: Main: 138665984 16:01:24 INFO - 2015-11-24 16:01:24,874 DEBUG : BROWSER_OUTPUT: 16:01:27 INFO - 2015-11-24 16:01:27,081 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41153/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:01:27 INFO - 2015-11-24 16:01:27,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 140365824 16:01:27 INFO - 2015-11-24 16:01:27,670 DEBUG : BROWSER_OUTPUT: 16:01:30 INFO - 2015-11-24 16:01:30,670 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41153/tests/kraken/driver.html?testName=imaging-darkroom) 16:01:31 INFO - 2015-11-24 16:01:31,256 DEBUG : BROWSER_OUTPUT: RSS: Main: 140701696 16:01:31 INFO - 2015-11-24 16:01:31,256 DEBUG : BROWSER_OUTPUT: 16:01:34 INFO - 2015-11-24 16:01:34,651 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41153/tests/kraken/driver.html?testName=imaging-desaturate) 16:01:35 INFO - 2015-11-24 16:01:35,244 DEBUG : BROWSER_OUTPUT: RSS: Main: 140836864 16:01:35 INFO - 2015-11-24 16:01:35,244 DEBUG : BROWSER_OUTPUT: 16:01:38 INFO - 2015-11-24 16:01:38,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41153/tests/kraken/driver.html?testName=json-parse-financial) 16:01:38 INFO - 2015-11-24 16:01:38,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 140808192 16:01:38 INFO - 2015-11-24 16:01:38,700 DEBUG : BROWSER_OUTPUT: 16:01:40 INFO - 2015-11-24 16:01:40,308 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41153/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:01:40 INFO - 2015-11-24 16:01:40,890 DEBUG : BROWSER_OUTPUT: RSS: Main: 140349440 16:01:40 INFO - 2015-11-24 16:01:40,890 DEBUG : BROWSER_OUTPUT: 16:01:42 INFO - 2015-11-24 16:01:42,721 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:01:43 INFO - 2015-11-24 16:01:43,327 DEBUG : BROWSER_OUTPUT: RSS: Main: 140472320 16:01:43 INFO - 2015-11-24 16:01:43,327 DEBUG : BROWSER_OUTPUT: 16:01:45 INFO - 2015-11-24 16:01:45,411 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:01:45 INFO - 2015-11-24 16:01:45,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 140492800 16:01:45 INFO - 2015-11-24 16:01:45,997 DEBUG : BROWSER_OUTPUT: 16:01:48 INFO - 2015-11-24 16:01:48,484 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:01:49 INFO - 2015-11-24 16:01:49,073 DEBUG : BROWSER_OUTPUT: RSS: Main: 140505088 16:01:49 INFO - 2015-11-24 16:01:49,074 DEBUG : BROWSER_OUTPUT: 16:01:51 INFO - 2015-11-24 16:01:51,649 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:01:52 INFO - 2015-11-24 16:01:52,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 139935744 16:01:52 INFO - 2015-11-24 16:01:52,235 DEBUG : BROWSER_OUTPUT: 16:01:53 INFO - 2015-11-24 16:01:53,904 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41153/tests/kraken/driver.html?testName=ai-astar) 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 140128256 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;105;111;111;113;124;111;110;111;111 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;125;160;164;135;155;152;132;166;128 16:01:54 INFO - 2015-11-24 16:01:54,155 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;161;163;164;164;161;164;169;162 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;70;72;72;73;132;73;71;71 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;141;93;93;94;138;94;94;135 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;111;110;110;110;110;110;109;109;109 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;145;147;146;147;146;146;147;147;146 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;95;96;96;95;96;95;96;96;97 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;65;64;67;64;63;63;64;65;64;63 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;68;71;69;69;68;68;70;69 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;83;87;85;85;84;88;85;84;84 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;124;121;122;119;123;136;120;134;132 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;149;154;141;152;149;149;149;154;150 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;67;67;70;70;73;69;67;70;67 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,718 16:01:54 INFO - 2015-11-24 16:01:54,156 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:01:54 INFO - 2015-11-24 16:01:54,157 DEBUG : BROWSER_OUTPUT: __startTimestamp1448409714154__endTimestamp 16:01:54 INFO - 2015-11-24 16:01:54,157 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,160 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.80 Median:111.00 stddev:4.76 (4.3%) stddev-sans-first:5.04 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: Values: 111.0 105.0 111.0 111.0 113.0 124.0 111.0 110.0 111.0 111.0 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:147.30 Median:155.50 stddev:15.64 (10.1%) stddev-sans-first:16.27 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: Values: 156.0 125.0 160.0 164.0 135.0 155.0 152.0 132.0 166.0 128.0 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.10 Median:163.50 stddev:2.42 (1.5%) stddev-sans-first:2.54 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 161.0 163.0 164.0 164.0 161.0 164.0 169.0 162.0 16:01:54 INFO - 2015-11-24 16:01:54,162 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:77.80 Median:72.00 stddev:19.07 (26.5%) stddev-sans-first:20.11 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 70.0 72.0 72.0 73.0 132.0 73.0 71.0 71.0 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.80 Median:93.00 stddev:21.58 (23.2%) stddev-sans-first:22.30 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 141.0 93.0 93.0 94.0 138.0 94.0 94.0 135.0 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 110.0 110.0 110.0 110.0 110.0 109.0 109.0 109.0 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.85 (0.6%) stddev-sans-first:0.71 16:01:54 INFO - 2015-11-24 16:01:54,163 DEBUG : BROWSER_OUTPUT: Values: 148.0 145.0 147.0 146.0 147.0 146.0 146.0 147.0 147.0 146.0 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: Values: 97.0 95.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 97.0 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:1.23 (1.9%) stddev-sans-first:1.27 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 67.0 64.0 63.0 63.0 64.0 65.0 64.0 63.0 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 68.0 71.0 69.0 69.0 68.0 68.0 70.0 69.0 16:01:54 INFO - 2015-11-24 16:01:54,164 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:1.62 (1.9%) stddev-sans-first:1.58 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: Values: 83.0 83.0 87.0 85.0 85.0 84.0 88.0 85.0 84.0 84.0 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:123.50 stddev:6.38 (5.2%) stddev-sans-first:6.50 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: Values: 120.0 124.0 121.0 122.0 119.0 123.0 136.0 120.0 134.0 132.0 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.60 Median:149.50 stddev:4.97 (3.3%) stddev-sans-first:3.87 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: Values: 139.0 149.0 154.0 141.0 152.0 149.0 149.0 149.0 154.0 150.0 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.50 stddev:1.97 (2.8%) stddev-sans-first:2.09 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: Values: 69.0 67.0 67.0 70.0 70.0 73.0 69.0 67.0 70.0 67.0 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:01:54 INFO - 2015-11-24 16:01:54,165 DEBUG : BROWSER_OUTPUT: 16:01:55 INFO - 2015-11-24 16:01:55,197 INFO : Browser exited with error code: 0 16:01:55 INFO - 2015-11-24 16:01:55,245 INFO : Completed test kraken (00:00:59) 16:01:55 INFO - 2015-11-24 16:01:55,245 INFO : Starting test v8_7 16:01:55 INFO - 2015-11-24 16:01:55,245 DEBUG : operating with platform_type : linux_ 16:01:55 INFO - 2015-11-24 16:01:55,245 INFO : Initialising browser for v8_7 test... 16:01:55 INFO - 2015-11-24 16:01:55,251 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdWcWHs/profile http://localhost:41153/getInfo.html 16:01:59 INFO - 2015-11-24 16:01:59,654 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:01:59 INFO - 2015-11-24 16:01:59,654 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:01:59 INFO - 2015-11-24 16:01:59,654 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:01:59 INFO - 2015-11-24 16:01:59,654 DEBUG : BROWSER_OUTPUT: __metrics 16:02:00 INFO - 2015-11-24 16:02:00,693 INFO : Browser initialized. 16:02:00 INFO - 2015-11-24 16:02:00,693 INFO : Running cycle 1/1 for v8_7 test... 16:02:00 INFO - 2015-11-24 16:02:00,694 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdWcWHs/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:02:12 INFO - 2015-11-24 16:02:12,404 DEBUG : BROWSER_OUTPUT: RSS: Main: 142422016 16:02:12 INFO - 2015-11-24 16:02:12,404 DEBUG : BROWSER_OUTPUT: 16:02:33 INFO - 2015-11-24 16:02:33,811 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41153/tests/v8_7/run.html (next: http://localhost:41153/tests/v8_7/run.html) 16:02:34 INFO - 2015-11-24 16:02:34,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 138878976 16:02:34 INFO - 2015-11-24 16:02:34,070 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,070 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:02:34 INFO - 2015-11-24 16:02:34,070 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:02:34 INFO - 2015-11-24 16:02:34,070 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:02:34 INFO - 2015-11-24 16:02:34,070 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |0;Richards;185.9427296392711 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.4557351809084 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.97780275358247 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5045.226130653266 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1053.740779768177 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |5;Earley;708.9235127478754 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |6;Boyer;12072.289156626506 16:02:34 INFO - 2015-11-24 16:02:34,071 DEBUG : BROWSER_OUTPUT: |7;RegExp;24853.658536585364 16:02:34 INFO - 2015-11-24 16:02:34,072 DEBUG : BROWSER_OUTPUT: |8;Splay;574.052812858783 16:02:34 INFO - 2015-11-24 16:02:34,072 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 16:02:34 INFO - 2015-11-24 16:02:34,072 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:02:34 INFO - 2015-11-24 16:02:34,072 DEBUG : BROWSER_OUTPUT: __startTimestamp1448409754064__endTimestamp 16:02:34 INFO - 2015-11-24 16:02:34,072 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,072 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:02:34 INFO - 2015-11-24 16:02:34,072 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:185.94 Median:185.94 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: Values: 185.9 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.46 Median:192.46 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: Values: 192.5 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.98 Median:280.98 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: Values: 281.0 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5045.23 Median:5045.23 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: Values: 5045.2 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1053.74 Median:1053.74 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,073 DEBUG : BROWSER_OUTPUT: Values: 1053.7 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:708.92 Median:708.92 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: Values: 708.9 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12072.29 Median:12072.29 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: Values: 12072.3 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24853.66 Median:24853.66 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: Values: 24853.7 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:574.05 Median:574.05 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: Values: 574.1 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: Values: 4840.6 16:02:34 INFO - 2015-11-24 16:02:34,074 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:02:34 INFO - 2015-11-24 16:02:34,075 DEBUG : BROWSER_OUTPUT: 16:02:34 INFO - 2015-11-24 16:02:34,937 INFO : Browser exited with error code: 0 16:02:34 INFO - 2015-11-24 16:02:34,951 INFO : Completed test v8_7 (00:00:39) 16:02:35 INFO - 2015-11-24 16:02:35,089 INFO : Completed test suite (00:20:11) 16:02:35 INFO - 2015-11-24 16:02:35,089 DEBUG : Working with test: dromaeo_css 16:02:35 INFO - 2015-11-24 16:02:35,089 DEBUG : Generating results file: dromaeo_css 16:02:35 INFO - 2015-11-24 16:02:35,090 DEBUG : Working with test: kraken 16:02:35 INFO - 2015-11-24 16:02:35,091 DEBUG : Generating results file: kraken 16:02:35 INFO - 2015-11-24 16:02:35,091 INFO : javascript benchmark 16:02:35 INFO - 2015-11-24 16:02:35,091 DEBUG : Working with test: v8_7 16:02:35 INFO - 2015-11-24 16:02:35,091 DEBUG : Generating results file: v8_7 16:02:35 INFO - 2015-11-24 16:02:35,092 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:02:35 INFO - 2015-11-24 16:02:35,150 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 16:02:35 INFO - 2015-11-24 16:02:35,150 DEBUG : process_Request line: dromaeo_css 7021.11 graph.html#tests=[[72,64,43]] 16:02:35 INFO - 2015-11-24 16:02:35,151 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:02:35 INFO - 2015-11-24 16:02:35,194 DEBUG : process_Request line: kraken 1519.50 graph.html#tests=[[232,64,43]] 16:02:35 INFO - 2015-11-24 16:02:35,195 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:02:35 INFO - 2015-11-24 16:02:35,230 DEBUG : process_Request line: v8_7 21352.93 graph.html#tests=[[230,64,43]] 16:02:35 INFO - 2015-11-24 16:02:35,231 INFO : javascript benchmark 16:02:35 INFO - 2015-11-24 16:02:35,235 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3256.772025958184, 3268.23196548811, 3309.1230823831675, 3309.238875058737, 3316.3035762489876, 5276.34045744282, 5522.944770552293, 5555.250041244431, 5566.55467562595, 5569.164625306211, 5381.246625472427, 5431.293931788872, 5494.066008778507, 5639.915401268984, 5642.7178641067985, 4984.77568509418, 5039.067772462088, 5082.212257100137, 5114.181730923053, 5115.616328775357, 5042.420121685998, 5085.771140298695, 5124.385073791151, 5144.511271429208, 5217.37391513019, 4875.171220892421, 4953.157963146274, 5020.347354843883, 5077.466865979074, 5133.435322114565, 5039.47085556015, 5040.243963405482, 5048.066107770038, 5073.949260507385, 5101.617378696613, 4930.753462326869, 4991.575716064139, 5001.274815151783, 5013.824516141917, 5018.7490625468945, 5172.146595811687, 5217.739113044333, 5274.26160337553, 5322.840314790524, 5332.3601167859715, 5980.13288073227, 6005.39946005396, 6008.8497787555225, 6009.849753756147, 6020.277566692025, 6057.727402266909, 6058.606190597597, 6058.909116363258, 6059.0608455689435, 6061.393860613947, 5365.543928766059, 5477.698726570037, 5540.944590554123, 5616.9157462638095, 5618.63478873876, 5954.46409823118, 5965.642061476326, 6022.337542870281, 6035.275766907999, 6049.939500604937, 5915.319738230072, 5927.288725352985, 5930.614510056833, 5932.436418540232, 5937.940620593825, 5741.62679425836, 5947.345791962881, 5952.0476723724, 5959.433853783884, 6007.609505382224, 5813.82558523245, 5854.385289544622, 5899.085641725539, 6000.759969601167, 6050.878982420327, 5988.700564971734, 5990.5507086968655, 5992.730327135289, 5994.790182343597, 6034.2155519782145, 4444.599986001275, 4575.084983003413, 4581.610563102107, 4590.9540904591195, 4628.583427491542, 4037.475128233311, 4040.7979601019833, 4087.7751723655483, 4107.6508496777515, 4119.402686610425, 5693.373728889819, 5730.971345143247, 5741.942580574224, 5776.797812076557, 5801.158831969341, 5758.0, 5893.174955506232, 5949.553783466257, 5958.553108516879, 6019.277686677627, 5775.740091695884, 5825.76696932118, 5842.036064049385, 5842.2405087338375, 5886.293644762571, 5218.086834803894, 5227.111391063529, 5231.633785634969, 5236.16221404581, 5244.370675518886, 5953.493953013955, 6111.816645500642, 6215.689215539295, 6260.0, 6283.151774510474, 5988.071848863433, 6039.275286965592, 6046.879062418639, 6060.848478788065, 6073.665948372705, 6124.8162555121635, 6207.0, 6208.627482351073, 6269.059641053696, 6270.623762574261, 5763.884722305615, 5825.446582574648, 5878.147668588116, 5889.440503152194, 5987.880242395215, 5982.46157845796, 6007.669578173242, 6022.307434645072, 6052.485538729081, 6071.423214794588, 5902.321233058082, 5905.261842269716, 5914.556408269483, 5934.6142500737315, 5935.851603709769, 5643.689597072201, 5751.798687045934, 5774.538036956949, 5897.144913987362, 5922.7927022554, 5784.5083167929515, 5798.188253644409, 5853.0635098384055, 5855.560832937462, 5880.206172166702, 5737.454941780524, 5924.0521516558865, 5965.6122352048715, 5974.2830860297045, 6008.849778755566, 5551.916721249104, 5669.574781891292, 5759.769609215585, 5773.942260577341, 5833.387494313036], "unit": "ms", "name": "dojo.html", "value": 5505.341473578982}, {"lowerIsBetter": false, "replicates": [556.510270961554, 578.5718568259489, 604.8487878030493, 613.7483631710998, 625.5839866488782, 39599.0, 39937.60062399375, 40199.39700904481, 40353.38586456542, 40425.0, 42631.78684106583, 43310.0, 43358.349624755574, 43425.0, 43428.78285608567, 8603.311735061212, 8745.693900713477, 8785.121487851227, 8789.824203515958, 8798.42810217337, 8545.914540854605, 8711.825763484727, 8755.474671519698, 8782.868256976151, 8786.121387861194, 8737.606807693637, 8830.911690883106, 8835.204831565157, 8840.734777956639, 8846.20384165425, 8811.823763524693, 8815.867761983576, 8837.911620883773, 8853.380263381567, 8906.51014194219, 8624.051354351017, 8641.092685268019, 8665.220130188314, 8725.738227853175, 8753.606087726068, 41335.7933210335, 41471.37792933109, 41706.0, 41891.0, 42140.367894481606, 3098.597182366278, 3205.37495188436, 3247.1395080303737, 3309.973908088476, 3571.624979377154, 30289.394212115854, 30711.69288307133, 30769.538456923165, 31227.687723122704, 31332.843335783175, 3765.2093060457064, 3774.924501509982, 3837.3860182370686, 3853.9229215415535, 3952.367621180626, 261914.0, 262058.0, 262477.0, 263025.0, 263507.6824615884, 56662.433375665714, 58194.70902645544, 58472.41527584755, 58481.41518584845, 58559.70720146372, 53674.0, 61076.0, 61958.0, 62196.37803621906, 62527.37472625307, 15101.244937753068, 15225.086494810346, 15341.309641066178, 15377.0, 15493.302801373966, 11189.776204475866, 11368.261063030877, 11403.201775875776, 11437.856214378495, 11504.884951150549, 19694.606107878048, 19924.0, 19955.0, 20011.099500522512, 20108.89945550263, 7993.560354180576, 8173.877391839127, 8254.504729716236, 8272.669093236324, 8305.169483051768, 932.305432452827, 974.5663179884883, 995.5470261031213, 1008.2589296866797, 1098.566066403782, 28729.138125856258, 28816.279593010342, 28948.13155605335, 28966.0, 29298.267543311587, 1068.5458680060942, 1154.982675259855, 1179.4161889864572, 1198.874118217592, 1209.5524655877205, 14810.54144514671, 14811.407543698348, 14859.108453492609, 14903.088148255016, 14960.326785294688, 320243.0, 325323.0, 326037.0, 326061.0, 326340.0, 53649.46350536523, 53943.0, 53978.46021539813, 54038.0, 54041.0, 1367.8153449284218, 1367.9179249244878, 1376.0, 1377.8415482219473, 1383.619504636213, 27728.168154955383, 28149.859250704434, 28261.858690706416, 28558.42883142367, 28738.281542961595, 46710.299345510524, 46999.53000469952, 47324.290135648684, 47410.52589474199, 47427.288590670476, 295371.0, 295596.0, 295650.0, 296388.0, 297473.0, 31040.379192416476, 31900.202494937814, 32022.0, 32143.35713285768, 32471.350572987936, 38404.0, 38769.418458722575, 38908.221835563694, 39330.41004384994, 39451.2109757809, 34243.82878085594, 36297.81851090727, 36491.81754091319, 36523.63476365202, 36867.07832304214, 35975.460368095024, 36125.27749444944, 36360.454593181654, 36444.45333319949, 36635.63364366429, 35550.26722194132, 35699.06149237088, 35995.0, 36094.4585831218, 36184.27631447409, 31602.525962111045, 32069.198270043435, 33272.500912486816, 33343.49984750279, 33377.83311083429, 25138.62292065584, 25362.74637253604, 25364.0, 25438.76750196214, 25481.0], "unit": "ms", "name": "ext.html", "value": 18623.259098183}, {"lowerIsBetter": false, "replicates": [348.9585922354228, 359.4051844197852, 363.99272014559705, 365.0690738616527, 367.1958411079737, 16699.24853381598, 17334.046627435488, 17670.116494175272, 17922.462326130222, 18333.266669333243, 11057.170712196577, 12206.450709718061, 16036.797240207003, 16051.438199663015, 16094.59763505913, 4374.693771436001, 5424.918626220609, 5438.646487978289, 5439.809606663767, 5448.700321482317, 5389.218563308337, 5457.617966742329, 5465.480779325958, 5466.0, 5468.507834294913, 4954.777035033432, 5011.098002359574, 5084.288199652052, 5221.2951251580935, 5337.626366154371, 5285.947140528584, 5307.52232299095, 5310.62825602208, 5318.707471089088, 5345.465453454663, 5079.390473143247, 5217.373915130171, 5234.42421333653, 5249.317588713443, 5253.212018197301, 12525.74948501034, 12718.491260349574, 15762.0, 20630.690539641917, 20633.793662063337, 2017.1729590867674, 2033.379819155157, 2051.138521820839, 2069.513664288906, 2160.2115227941845, 9630.903690963141, 9725.708228753077, 9752.073553012453, 9787.657431989917, 11559.959603635629, 1770.5750619851142, 1837.012511032553, 1845.8707890447674, 1853.13829069482, 1856.2574970011967, 59568.0, 62186.37813621851, 63466.0, 63733.0, 63805.680971595306, 10059.949700251453, 10132.645357412603, 10309.639162629272, 10396.844047339297, 10444.21668374875, 13485.662858428617, 13558.864411355957, 13575.864241357658, 13586.592402227949, 13652.453901843815, 7363.521371110861, 7501.699932002659, 7525.397968162534, 7569.129550101698, 7639.159692433828, 7198.676059577331, 7255.165655949528, 7277.126744790553, 7299.59852208123, 7301.853962920712, 4432.800523976429, 4477.977610111929, 4482.125985432809, 4492.932606010912, 4564.109998550251, 1823.471193353939, 1823.972640410395, 1856.7957524672274, 1869.4952362861952, 1888.4145914766466, 897.4077109108088, 952.5570609666538, 960.2270172511056, 986.7379255740358, 988.1650005745134, 16133.193340332937, 16310.836891631168, 16578.336866525446, 16703.0, 17316.826831731774, 869.7303835810919, 880.4937161132375, 891.2647066170435, 917.312015988009, 920.963161473547, 9378.577963991636, 9434.48110353937, 9545.52272386378, 9784.412935223909, 10035.247356448295, 484368.0, 488935.0, 490840.0, 493190.0, 517419.0, 79038.60480697559, 82499.17500825036, 83839.0, 84034.5798271017, 84087.59124087665, 1120.2158489057692, 1131.264677959333, 1144.09616403044, 1153.3022521374448, 1153.9942300288444, 14858.257087145383, 15516.22418879074, 15564.299606517507, 15572.299246533707, 15870.2064896757, 31869.0, 32447.0, 33282.16794580155, 33732.3253534923, 33974.830125849214, 41466.170676585694, 41478.58521414868, 42236.36645450382, 42421.36367954545, 46277.30584041309, 9376.437413755197, 9562.474063926273, 9578.23374130054, 10133.543990520297, 10181.694549163536, 17653.382131625844, 17851.0, 17884.10579471047, 18212.36256731008, 18399.080045997915, 13428.664283392602, 14040.1575905446, 14091.929540352232, 15154.469593564614, 15185.468508602145, 13902.0, 14369.928150359181, 14846.554603361916, 15036.924815375853, 15078.246087695352, 15388.307526161574, 15518.612034699223, 15612.375504979673, 15815.288312025756, 15822.367105315658, 9862.654807081717, 9908.157806586521, 9912.256580756617, 9942.552585133832, 10065.0, 10468.63359782404, 12805.423755931191, 14591.0, 14600.12399256048, 15294.77057844111], "unit": "ms", "name": "jquery.html", "value": 8799.895149621696}, {"lowerIsBetter": false, "replicates": [1506.0888162661583, 1654.0985163086118, 1721.956951076223, 1732.4023211991866, 1772.849307808837, 6346.174997250351, 6469.644169570672, 7462.813429664255, 7834.999999999993, 7850.175731548191, 7228.457865660083, 7405.5556666599905, 7465.626718664059, 7469.215732348093, 7477.663505142268, 2167.783221677835, 2223.6442169252923, 2227.27613525604, 2229.8216142708543, 2234.318532847481, 2240.73111226653, 2263.5246598214394, 2293.1859189987563, 2304.2626359564947, 2306.8269879759, 2233.3746550965675, 2252.7521972583004, 2270.6707527408516, 2274.80664143548, 2294.059435631383, 2224.744154422246, 2229.2977712020825, 2266.150193677371, 2267.331137314496, 2267.8072363849174, 2240.1263507232193, 2243.4279258789065, 2253.794220092243, 2263.1626298269744, 2264.388615073937, 7570.280823321831, 7666.769996900102, 7728.6522106505345, 7729.072511298624, 7763.805904852367, 4604.746738929358, 4652.999605084923, 4657.371254880615, 4667.253239481701, 4678.830292426894, 2982.4631566318053, 3032.7725420593324, 3121.562981182636, 3133.5143052826847, 3159.41550813098, 1042.1766804224653, 1054.3463052907246, 1756.1482680899753, 1807.9909600451913, 1818.4999125240533, 10203.336783109075, 10321.329113607591, 10361.585536578528, 10361.844572331422, 10369.844452333222, 4026.355783074694, 4089.997950502146, 4101.364288535282, 4161.8335266589, 4171.395147703566, 8547.700830470903, 8590.441621294596, 8594.527300998505, 8613.784655383666, 8619.1811777881, 4567.931481027787, 4568.703034302826, 4588.2888152336445, 4591.9081618367445, 4601.700889442175, 4212.852550160791, 4228.9788551057045, 4243.27864263076, 4384.583464570861, 4391.912161756747, 3615.728820338485, 3617.5478065241846, 3640.7087433005295, 3660.853565857336, 3665.065408320861, 1109.8890110988998, 1132.943352832355, 1136.971575710614, 1137.056243318044, 1148.1847887999613, 1038.896110388955, 1040.7502199472224, 1044.9216308776872, 1175.3887978251432, 1318.9340532973313, 6714.227863795629, 6769.695363708645, 6789.0, 6818.795436136924, 6834.8633027340165, 491.9262110683355, 493.247797109411, 496.99006019880125, 501.00350104832216, 503.71540079854765, 5442.401335853053, 5455.754491047913, 5500.42245564218, 5502.972485137549, 5519.862003449946, 8817.515036673045, 8877.402067627889, 8995.640174393082, 9084.227840633488, 9139.314551408672, 8997.505137217386, 9015.864762028576, 9039.593218305192, 9055.501947392957, 9066.682666106786, 6548.476121910331, 6698.196216454154, 6721.462283017347, 6940.548864323803, 6953.89569156463, 6975.895361569479, 6989.615571143636, 6991.335823096798, 7013.964930175316, 7014.123234595676, 7848.2151784823345, 7978.680852765824, 8028.83942321162, 8055.7583272501915, 8157.8368432632205, 8179.550124743197, 8255.545944973084, 8285.875711864448, 8341.24928756415, 8378.58107094655, 5747.281589801275, 5889.146073819358, 6701.195856497252, 6721.462283017445, 6833.350831670983, 7080.327368899946, 7122.786316410516, 7164.820879478055, 7197.712091516491, 7208.639568021683, 6601.801945941629, 6631.701573429111, 6668.766593169215, 6815.693293801692, 6928.099347084847, 6573.57271777333, 6649.235337936199, 6711.228208756055, 6758.695858686469, 6796.2524122347495, 6615.04081908111, 6768.2216545097945, 6809.455243580601, 6814.62519561409, 6822.624755638289, 5893.675847828239, 5918.704064796829, 5935.940640593539, 5953.761849526144, 5965.612235204698, 6025.427584379477, 6029.095635654687, 6079.240094988127, 6080.969595151996, 6082.878342433215], "unit": "ms", "name": "mootools.html", "value": 4307.083229174401}, {"lowerIsBetter": false, "replicates": [336.86357025404715, 339.3772427595362, 344.78278684428807, 347.42154313068744, 347.5360393874177, 6279.937200627993, 6292.244930608334, 6378.362163783625, 6399.328070552595, 6466.0, 6247.5001999840015, 6258.155149054876, 6298.968505157468, 6304.71628776705, 6308.243010838706, 4765.475797662263, 4780.784864681098, 4781.115493633689, 4784.736839473828, 4792.784324705379, 4784.306275590037, 4789.401324834395, 4792.856214313576, 4796.952030479685, 4799.088173247089, 4653.6044436222965, 4758.048390321932, 4763.49983251757, 4768.952310476886, 4773.57037866592, 4736.242201247801, 4754.429468463789, 4762.571368576845, 4765.833195838164, 4769.070031343889, 4083.591640835922, 4114.0, 4116.0, 4116.259073366793, 4120.608542188487, 6021.42796434338, 6022.759089636409, 6027.547933904931, 6029.125776762388, 6044.758209671608, 5328.733563321818, 5505.366882808448, 5505.788726480168, 5520.475554822286, 5528.9723551381985, 6666.999950007489, 6674.766383176566, 6675.332466753334, 6684.431823294977, 6687.331266873322, 4541.795619197111, 4548.204064288736, 4552.590266875964, 4556.453225612914, 4561.475430325489, 6732.124823772927, 6788.456923446161, 6875.3812156905615, 6879.589684114744, 6886.793396198122, 4580.335851301543, 4585.266357382803, 4585.403897493305, 4601.861944141681, 4622.745748983771, 4667.346571479996, 4668.416447944007, 4688.367070445514, 4689.929651055237, 4696.882577935579, 5490.588205884575, 5491.945080549223, 5495.2856128703015, 5498.092814685532, 5499.395066542677, 6525.282218955911, 6707.49693772969, 6711.161104861892, 6746.966265168643, 6756.3919247266995, 1628.8126865410393, 1654.3713388912136, 1663.035439445119, 1666.0753281928646, 1666.433412639684, 1079.3092420850662, 1081.8431327457631, 1084.7450849050467, 1107.8227483602584, 1112.9833052504218, 6447.451966582893, 6463.353664633593, 6464.515161362822, 6482.254540727877, 6502.869942601216, 6806.4554835613035, 6810.489213309022, 6823.624700641512, 6823.761168359084, 6823.931760682429, 6444.871102578016, 6467.126937863422, 6485.805425837232, 6487.513436492282, 6490.902636460457, 3729.4032954727113, 3768.0, 3787.034306251888, 3799.962000379961, 3809.447630093621, 7304.598247096461, 7321.450891183076, 7340.229275925951, 7357.117145942414, 7360.926390736131, 7004.229534751174, 7130.465215108888, 7153.642317884085, 7175.712971481186, 7180.497365184491, 7263.273672632695, 7311.415086793044, 7315.743948961761, 7321.231270716498, 7341.70633174667, 6945.548539344926, 6949.791506254821, 6950.895736564057, 6957.339052789976, 6966.756163534252, 6653.800385988428, 6766.661666916733, 6814.693338799667, 6855.348741869514, 6857.485688573477, 7283.7450689225625, 7314.0, 7321.267873212849, 7326.120865496175, 7338.339549440576, 4611.2852507861335, 4627.652926030494, 4641.234196357564, 4645.3496510487885, 4645.350900430433, 6805.319468053155, 6928.237893831775, 6928.341807528277, 6930.0, 6941.09765730472, 6714.160729908762, 6754.797356079325, 6775.119234499483, 6777.661116944231, 6783.287754785676, 6750.8649827004165, 6763.154605674291, 6768.2216545097945, 6773.491988101011, 6774.661266936535, 6378.0, 6575.638339891352, 6580.210374754868, 6586.50601204902, 6640.605472850755, 6827.590344579341, 6833.624150671761, 6836.658167091526, 6843.076184715, 6846.110005699227, 6511.869762604627, 6531.934680653133, 6538.509611779041, 6582.17722784652, 6584.209894812461], "unit": "ms", "name": "prototype.html", "value": 5026.491355321268}, {"lowerIsBetter": false, "replicates": [714.928507149285, 788.0622059748902, 790.1720487819418, 816.0900595835643, 832.3299743706314, 12695.555655552045, 12937.029722770807, 12945.352732363392, 12957.09300348976, 12958.80561791574, 12896.355182240897, 12977.416016279267, 13002.479900803957, 13114.016448766333, 13119.803202951964, 811.123986095019, 836.5315423362936, 837.3970741066422, 837.4975014990987, 837.6481877611418, 687.6149356360429, 698.4726531468734, 811.2212276214841, 828.2545708862042, 835.7116794705817, 857.0486759696731, 863.1196179896501, 864.4726716702806, 867.0679020053436, 867.4621734524573, 840.3781201910604, 849.6941101203562, 852.0457088061349, 857.0829212742368, 865.6364326982651, 634.5019159959456, 634.784173381048, 635.3170341882495, 636.9585976911486, 640.9551331406803, 13575.049746517747, 13632.113912595749, 13696.109752866032, 13700.3834827433, 13707.588772336845, 11502.942485287604, 12306.81539776904, 12578.937105314506, 12704.80942785859, 12722.427490762939, 9450.85823712645, 10005.79988400228, 10356.119729822898, 10556.099902017633, 10677.41274229917, 3501.9824900875587, 3571.499990001402, 3591.0, 3636.054625797285, 3680.5215322008235, 50158.7492062541, 50370.7481462594, 50449.24326135111, 50498.74750626223, 50589.494105058846, 9605.47169905648, 9676.419414835133, 9818.0, 9842.114209721016, 9997.200223982063, 18253.26986920535, 18272.0, 18492.537686557942, 18528.907355463136, 18538.0, 7282.890756638654, 7283.271672832674, 7366.373858222111, 7472.402207823361, 7547.886781698279, 4230.280852255062, 4248.511421186541, 4311.180875633682, 4338.197433474754, 4348.326009468537, 3043.6956304369387, 3055.282008727947, 3065.693430656961, 3087.8919237826567, 3090.196548897303, 505.9392872855207, 521.2467983763426, 529.5895680847298, 536.243896106488, 541.1314839682332, 498.076068892207, 561.9659825920327, 612.5240351259724, 642.6401215319436, 668.7859884836805, 8439.746807595782, 8637.697680581276, 8650.480971141626, 8682.86975695365, 8801.779955501164, 532.2575007864008, 561.4974597735086, 649.0556240669814, 660.5013215022627, 669.5079116849112, 3614.9277014459562, 4028.093678922278, 4050.311447054008, 4115.279826030433, 4154.750714957052, 57845.0, 57847.0, 58034.0, 58680.41319586835, 59156.704216479506, 51344.74327628338, 51787.74106129445, 51825.740871295406, 51833.7408312956, 52221.0, 45944.3108353375, 47029.5297047032, 47084.764576177586, 47279.0, 47474.5252547477, 8117.066537348044, 8132.064812546633, 8187.672493100447, 8218.096009439076, 8301.958490207511, 33825.830870845486, 35052.29895402129, 35193.120171995906, 35676.0, 36082.63917360793, 38900.221995560496, 40661.59338406578, 44433.77783111064, 44547.777261113486, 44673.55326446694, 9627.951860240653, 9667.419954802734, 9780.119789219005, 9877.0, 9982.301238913205, 10576.0, 10720.410377429316, 10799.298045627009, 10801.513931872927, 10838.187135964992, 9915.65295214664, 10249.33379330341, 10403.895961040293, 10477.974138790054, 10534.736631583964, 10533.262671612914, 10539.841902371318, 10673.141858340457, 10760.300580462557, 10882.183836212474, 9822.165115965223, 10026.097651211427, 10046.899531004889, 10166.542505587118, 10166.898331016891, 8718.17177368149, 8747.398381656609, 8796.428232164888, 8899.15458031486, 9071.591778369857, 8115.513069215866, 8424.915750842412, 8596.39825212254, 8754.649814007369, 8865.734027979172], "unit": "ms", "name": "yui.html", "value": 6132.410740454376}], "name": "dromaeo_css", "value": 7021.111857491675}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 105.0, 111.0, 111.0, 113.0, 124.0, 111.0, 110.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 111.8}, {"lowerIsBetter": true, "replicates": [156.0, 125.0, 160.0, 164.0, 135.0, 155.0, 152.0, 132.0, 166.0, 128.0], "unit": "ms", "name": "audio-beat-detection", "value": 147.3}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 161.0, 163.0, 164.0, 164.0, 161.0, 164.0, 169.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 163.1}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 70.0, 72.0, 72.0, 73.0, 132.0, 73.0, 71.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 77.8}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 141.0, 93.0, 93.0, 94.0, 138.0, 94.0, 94.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 106.8}, {"lowerIsBetter": true, "replicates": [109.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [148.0, 145.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.5}, {"lowerIsBetter": true, "replicates": [97.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 67.0, 64.0, 63.0, 63.0, 64.0, 65.0, 64.0, 63.0], "unit": "ms", "name": "json-parse-financial", "value": 64.2}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 68.0, 71.0, 69.0, 69.0, 68.0, 68.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.0}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 87.0, 85.0, 85.0, 84.0, 88.0, 85.0, 84.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.8}, {"lowerIsBetter": true, "replicates": [120.0, 124.0, 121.0, 122.0, 119.0, 123.0, 136.0, 120.0, 134.0, 132.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 125.1}, {"lowerIsBetter": true, "replicates": [139.0, 149.0, 154.0, 141.0, 152.0, 149.0, 149.0, 149.0, 154.0, 150.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.6}, {"lowerIsBetter": true, "replicates": [69.0, 67.0, 67.0, 70.0, 70.0, 73.0, 69.0, 67.0, 70.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.9}], "name": "kraken", "value": 1519.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [185.9427296392711], "unit": "score", "name": "Richards", "value": 189.85415600000005}, {"lowerIsBetter": false, "replicates": [192.4557351809084], "unit": "score", "name": "DeltaBlue", "value": 343.54912799999994}, {"lowerIsBetter": false, "replicates": [280.97780275358247], "unit": "score", "name": "Encrypt", "value": 947.3381789999996}, {"lowerIsBetter": false, "replicates": [5045.226130653266], "unit": "score", "name": "Decrypt", "value": 52.75898306772911}, {"lowerIsBetter": false, "replicates": [1053.740779768177], "unit": "score", "name": "RayTrace", "value": 702.2495610000001}, {"lowerIsBetter": false, "replicates": [708.9235127478754], "unit": "score", "name": "Earley", "value": 940.1056503496505}, {"lowerIsBetter": false, "replicates": [12072.289156626506], "unit": "score", "name": "Boyer", "value": 55.20601696606782}, {"lowerIsBetter": false, "replicates": [24853.658536585364], "unit": "score", "name": "RegExp", "value": 36.65395976447495}, {"lowerIsBetter": false, "replicates": [574.052812858783], "unit": "score", "name": "Splay", "value": 141.95732200000006}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21352.927233789167}]} 16:02:35 INFO - RETURN: dromaeo_css: 7021.11 16:02:35 INFO - RETURN: kraken: 1519.50 16:02:35 INFO - RETURN: v8_7: 21352.93 16:02:35 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21352.93"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1519.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7021.11"}}} 16:02:35 INFO - Return code: 0 16:02:35 INFO - # TBPL SUCCESS # 16:02:35 INFO - Running post-action listener: _resource_record_post_action 16:02:35 INFO - Running post-run listener: _resource_record_post_run 16:02:36 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 4096; Write bytes: 525729792; Read time: 8; Write time: 434452 16:02:36 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 153915392; Read time: 0; Write time: 166272 16:02:36 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 371814400; Read time: 8; Write time: 268180 16:02:36 INFO - Running post-run listener: _upload_blobber_files 16:02:36 INFO - Blob upload gear active. 16:02:36 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:02:36 INFO - Copying logs to upload dir... 16:02:36 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.050929 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-24 16:02:36.219608) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 16:02:36.222867) ========= 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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010064 build_url: 'https://queue.taskcluster.net/v1/task/sDvUxvG9T1m4MMYOh9Qddg/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-24 16:02:36.272269) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 16:02:36.272605) ========= 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=cbaddfaace6f82c7d20be4ba000001bb-1448408408.94163-1212305452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004571 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 16:02:36.322276) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 16:02:36.322588) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 16:02:36.322899) ========= ========= Total master_lag: 0.21 =========