builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1448033056.88 results: success (0) buildid: 20151120053001 builduid: 50d2cdfa0f9e413fa53ddf0b037f2fcf revision: 3d4f379b47fc12812f5e21657b63c6f426f5a063 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:16.882465) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:16.883623) ========= ========= Started set props: basedir (results: 0, elapsed: 5 secs) (at 2015-11-20 07:24:16.884441) ========= 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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.003944 basedir: '/builds/slave/test-pgo' ========= master_lag: 5.34 ========= ========= Finished set props: basedir (results: 0, elapsed: 5 secs) (at 2015-11-20 07:24:22.225714) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.226196) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.239350) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.239663) ========= 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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004704 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.290172) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.290500) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.291018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.291365) ========= 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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-20 07:24:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2015-11-20 07:24:22 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.167553 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.471614) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.471997) ========= 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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022578 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:22.521629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-20 07:24:22.522023) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3d4f379b47fc12812f5e21657b63c6f426f5a063 --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 3d4f379b47fc12812f5e21657b63c6f426f5a063 --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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-20 07:24:23,091 truncating revision to first 12 chars 2015-11-20 07:24:23,091 Setting DEBUG logging. 2015-11-20 07:24:23,091 attempt 1/10 2015-11-20 07:24:23,091 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3d4f379b47fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-20 07:24:24,171 unpacking tar archive at: fx-team-3d4f379b47fc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.339673 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-20 07:24:23.874895) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:23.875285) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:23.884305) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:23.884663) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-20 07:24:23.885174) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2015-11-20 07:24:23.885543) ========= /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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:24:24 INFO - MultiFileLogger online at 20151120 07:24:24 in /builds/slave/test-pgo 07:24:24 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 07:24:24 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 07:24:24 INFO - {'append_to_log': False, 07:24:24 INFO - 'base_work_dir': '/builds/slave/test-pgo', 07:24:24 INFO - 'blob_upload_branch': 'Fx-Team', 07:24:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 07:24:24 INFO - 'branch': 'Fx-Team', 07:24:24 INFO - 'buildbot_json_path': 'buildprops.json', 07:24:24 INFO - 'config_files': ('talos/linux_config.py',), 07:24:24 INFO - 'default_actions': ('clobber', 07:24:24 INFO - 'read-buildbot-config', 07:24:24 INFO - 'download-and-extract', 07:24:24 INFO - 'populate-webroot', 07:24:24 INFO - 'create-virtualenv', 07:24:24 INFO - 'install', 07:24:24 INFO - 'run-tests'), 07:24:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:24:24 INFO - 'download_minidump_stackwalk': True, 07:24:24 INFO - 'download_symbols': 'ondemand', 07:24:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:24:24 INFO - 'tooltool.py': '/tools/tooltool.py', 07:24:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:24:24 INFO - '/tools/misc-python/virtualenv.py')}, 07:24:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:24:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:24:24 INFO - 'installer_path': 'installer.exe', 07:24:24 INFO - 'log_level': 'info', 07:24:24 INFO - 'log_name': 'talos', 07:24:24 INFO - 'log_to_console': True, 07:24:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:24:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:24:24 INFO - 'opt_config_files': (), 07:24:24 INFO - 'pip_index': False, 07:24:24 INFO - 'sps_profile': False, 07:24:24 INFO - 'sps_profile_interval': 0, 07:24:24 INFO - 'suite': 'dromaeojs-e10s', 07:24:24 INFO - 'system_bits': '64', 07:24:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:24:24 INFO - 'title': 'talos-linux64-ix-015', 07:24:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:24:24 INFO - 'use_talos_json': True, 07:24:24 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 07:24:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:24:24 INFO - 'work_dir': 'build'} 07:24:24 INFO - ##### 07:24:24 INFO - ##### Running clobber step. 07:24:24 INFO - ##### 07:24:24 INFO - Running pre-action listener: _resource_record_pre_action 07:24:24 INFO - Running main action method: clobber 07:24:24 INFO - rmtree: /builds/slave/test-pgo/build 07:24:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 07:24:24 INFO - Running post-action listener: _resource_record_post_action 07:24:24 INFO - ##### 07:24:24 INFO - ##### Running read-buildbot-config step. 07:24:24 INFO - ##### 07:24:24 INFO - Running pre-action listener: _resource_record_pre_action 07:24:24 INFO - Running main action method: read_buildbot_config 07:24:24 INFO - Using buildbot properties: 07:24:24 INFO - { 07:24:24 INFO - "properties": { 07:24:24 INFO - "buildnumber": 67, 07:24:24 INFO - "product": "firefox", 07:24:24 INFO - "script_repo_revision": "production", 07:24:24 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 07:24:24 INFO - "repository": "", 07:24:24 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 07:24:24 INFO - "buildid": "20151120053001", 07:24:24 INFO - "slavename": "talos-linux64-ix-015", 07:24:24 INFO - "pgo_build": "True", 07:24:24 INFO - "basedir": "/builds/slave/test-pgo", 07:24:24 INFO - "project": "", 07:24:24 INFO - "platform": "ubuntu64_hw", 07:24:24 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:24:24 INFO - "slavebuilddir": "test", 07:24:24 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 07:24:24 INFO - "branch": "fx-team", 07:24:24 INFO - "repo_path": "integration/fx-team", 07:24:24 INFO - "stage_platform": "linux64-pgo", 07:24:24 INFO - "builduid": "50d2cdfa0f9e413fa53ddf0b037f2fcf", 07:24:24 INFO - "revision": "3d4f379b47fc12812f5e21657b63c6f426f5a063" 07:24:24 INFO - }, 07:24:24 INFO - "sourcestamp": { 07:24:24 INFO - "repository": "", 07:24:24 INFO - "hasPatch": false, 07:24:24 INFO - "project": "", 07:24:24 INFO - "branch": "fx-team-linux64-pgo-talos", 07:24:24 INFO - "changes": [ 07:24:24 INFO - { 07:24:24 INFO - "category": null, 07:24:24 INFO - "files": [ 07:24:24 INFO - { 07:24:24 INFO - "url": null, 07:24:24 INFO - "name": "https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 07:24:24 INFO - } 07:24:24 INFO - ], 07:24:24 INFO - "repository": "", 07:24:24 INFO - "rev": "3d4f379b47fc12812f5e21657b63c6f426f5a063", 07:24:24 INFO - "who": "sendchange", 07:24:24 INFO - "when": 1448032909, 07:24:24 INFO - "number": 6688823, 07:24:24 INFO - "comments": "", 07:24:24 INFO - "project": "", 07:24:24 INFO - "at": "Fri 20 Nov 2015 07:21:49", 07:24:24 INFO - "branch": "fx-team-linux64-pgo-talos", 07:24:24 INFO - "revlink": "", 07:24:24 INFO - "properties": [ 07:24:24 INFO - [ 07:24:24 INFO - "buildid", 07:24:24 INFO - "20151120053001", 07:24:24 INFO - "Change" 07:24:24 INFO - ], 07:24:24 INFO - [ 07:24:24 INFO - "builduid", 07:24:24 INFO - "50d2cdfa0f9e413fa53ddf0b037f2fcf", 07:24:24 INFO - "Change" 07:24:24 INFO - ], 07:24:24 INFO - [ 07:24:24 INFO - "pgo_build", 07:24:24 INFO - "True", 07:24:24 INFO - "Change" 07:24:24 INFO - ] 07:24:24 INFO - ], 07:24:24 INFO - "revision": "3d4f379b47fc12812f5e21657b63c6f426f5a063" 07:24:24 INFO - } 07:24:24 INFO - ], 07:24:24 INFO - "revision": "3d4f379b47fc12812f5e21657b63c6f426f5a063" 07:24:24 INFO - } 07:24:24 INFO - } 07:24:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 07:24:24 INFO - Running post-action listener: _resource_record_post_action 07:24:24 INFO - ##### 07:24:24 INFO - ##### Running download-and-extract step. 07:24:24 INFO - ##### 07:24:24 INFO - Running pre-action listener: _resource_record_pre_action 07:24:24 INFO - Running main action method: download_and_extract 07:24:24 INFO - mkdir: /builds/slave/test-pgo/build/tests 07:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:24 INFO - https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:24:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/test_packages.json 07:24:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/test_packages.json 07:24:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 07:24:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 07:24:25 INFO - Downloaded 1302 bytes. 07:24:25 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 07:24:25 INFO - Using the following test package requirements: 07:24:25 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:24:25 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:24:25 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:25 INFO - u'jsshell-linux-x86_64.zip'], 07:24:25 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:24:25 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:24:25 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:24:25 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:24:25 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:24:25 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:24:25 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:24:25 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:24:25 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:25 INFO - https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:24:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:24:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:24:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:27 INFO - Downloaded 22524052 bytes. 07:24:27 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 07:24:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:24:27 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 07:24:28 INFO - Return code: 0 07:24:28 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 07:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:28 INFO - https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:24:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:24:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:24:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:29 INFO - Downloaded 22524052 bytes. 07:24:29 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 07:24:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:24:29 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 07:24:30 INFO - Return code: 0 07:24:30 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')]} 07:24:30 INFO - https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:24:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:24:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:24:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:31 INFO - Downloaded 11130997 bytes. 07:24:31 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 07:24:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:24:31 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 07:24:31 INFO - Return code: 0 07:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:31 INFO - https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:24:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:24:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:24:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7G0AHU9ZTt6UuJdParsNvg/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 07:24:34 INFO - Downloaded 57347232 bytes. 07:24:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:24:34 INFO - mkdir: /builds/slave/test-pgo/properties 07:24:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 07:24:34 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 07:24:34 INFO - Contents: 07:24:34 INFO - build_url:https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:24:34 INFO - Running post-action listener: _resource_record_post_action 07:24:34 INFO - Running post-action listener: set_extra_try_arguments 07:24:34 INFO - ##### 07:24:34 INFO - ##### Running populate-webroot step. 07:24:34 INFO - ##### 07:24:34 INFO - Running pre-action listener: _resource_record_pre_action 07:24:34 INFO - Running main action method: populate_webroot 07:24:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:24:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:24:34 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 07:24:34 INFO - '../fennec_ids.txt'], 07:24:34 INFO - 'tests': ['tcheck2']}, 07:24:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:24:34 INFO - '--tppagecycles', 07:24:34 INFO - '7'], 07:24:34 INFO - 'tests': ['tsvgm']}}, 07:24:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:24:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:24:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:24:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:24:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'tests': ['damp', 'tps']}, 07:24:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['damp', 'tps']}, 07:24:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:24:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:24:34 INFO - 'other': {'tests': ['a11yr', 07:24:34 INFO - 'ts_paint', 07:24:34 INFO - 'tpaint', 07:24:34 INFO - 'sessionrestore', 07:24:34 INFO - 'sessionrestore_no_auto_restore']}, 07:24:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['a11yr', 07:24:34 INFO - 'ts_paint', 07:24:34 INFO - 'tpaint', 07:24:34 INFO - 'sessionrestore', 07:24:34 INFO - 'sessionrestore_no_auto_restore']}, 07:24:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['a11yr', 07:24:34 INFO - 'ts_paint', 07:24:34 INFO - 'tpaint', 07:24:34 INFO - 'sessionrestore', 07:24:34 INFO - 'sessionrestore_no_auto_restore']}, 07:24:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['a11yr', 07:24:34 INFO - 'ts_paint', 07:24:34 INFO - 'tpaint', 07:24:34 INFO - 'sessionrestore', 07:24:34 INFO - 'sessionrestore_no_auto_restore']}, 07:24:34 INFO - 'other_l64': {'tests': ['a11yr', 07:24:34 INFO - 'ts_paint', 07:24:34 INFO - 'tpaint', 07:24:34 INFO - 'sessionrestore', 07:24:34 INFO - 'sessionrestore_no_auto_restore']}, 07:24:34 INFO - 'other_nol64': {'tests': ['a11yr', 07:24:34 INFO - 'ts_paint', 07:24:34 INFO - 'tpaint', 07:24:34 INFO - 'sessionrestore', 07:24:34 INFO - 'sessionrestore_no_auto_restore']}, 07:24:34 INFO - 'svgr': {'tests': ['tsvgx', 07:24:34 INFO - 'tsvgr_opacity', 07:24:34 INFO - 'tart', 07:24:34 INFO - 'tscrollx', 07:24:34 INFO - 'cart']}, 07:24:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['tsvgx', 07:24:34 INFO - 'tsvgr_opacity', 07:24:34 INFO - 'tart', 07:24:34 INFO - 'tscrollx', 07:24:34 INFO - 'cart']}, 07:24:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'tests': ['tp5o']}, 07:24:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'talos_options': ['--e10s'], 07:24:34 INFO - 'tests': ['tp5o']}, 07:24:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'talos_options': ['--xperf_path', 07:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:34 INFO - 'tests': ['tp5n']}, 07:24:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:34 INFO - 'talos_options': ['--e10s', 07:24:34 INFO - '--xperf_path', 07:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:34 INFO - 'tests': ['tp5n']}}, 07:24:34 INFO - 'talos.zip': {'path': '', 07:24:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:24:34 INFO - Running post-action listener: _resource_record_post_action 07:24:34 INFO - ##### 07:24:34 INFO - ##### Running create-virtualenv step. 07:24:34 INFO - ##### 07:24:34 INFO - Running pre-action listener: _resource_record_pre_action 07:24:34 INFO - Running main action method: create_virtualenv 07:24:34 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 07:24:34 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 07:24:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 07:24:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:24:34 INFO - Using real prefix '/usr' 07:24:34 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 07:24:34 INFO - Installing distribute.............................................................................................................................................................................................done. 07:24:36 INFO - Installing pip.................done. 07:24:36 INFO - Return code: 0 07:24:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 07:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:36 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 0x7f164c9acc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ed6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ae6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', '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 07:24:36 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 07:24:36 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 07:24:36 INFO - Using env: {'DISPLAY': ':0', 07:24:36 INFO - 'HOME': '/home/cltbld', 07:24:36 INFO - 'LANG': 'en_US.UTF-8', 07:24:36 INFO - 'LANGUAGE': 'en_US:en', 07:24:36 INFO - 'LOGNAME': 'cltbld', 07:24:36 INFO - 'MAIL': '/var/mail/cltbld', 07:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:36 INFO - 'MOZ_NO_REMOTE': '1', 07:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:24:36 INFO - 'NO_EM_RESTART': '1', 07:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:24:36 INFO - 'PWD': '/builds/slave/test-pgo', 07:24:36 INFO - 'SHELL': '/bin/bash', 07:24:36 INFO - 'SHLVL': '1', 07:24:36 INFO - 'TERM': 'linux', 07:24:36 INFO - 'TMOUT': '86400', 07:24:36 INFO - 'USER': 'cltbld', 07:24:36 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:36 INFO - '_': '/tools/buildbot/bin/python'} 07:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:37 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 07:24:37 INFO - Cleaning up... 07:24:37 INFO - Return code: 0 07:24:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 07:24:37 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')]} 07:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:37 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')]} 07:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:37 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 0x7f164c9acc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ed6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ae6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', '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 07:24:37 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 07:24:37 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 07:24:37 INFO - Using env: {'DISPLAY': ':0', 07:24:37 INFO - 'HOME': '/home/cltbld', 07:24:37 INFO - 'LANG': 'en_US.UTF-8', 07:24:37 INFO - 'LANGUAGE': 'en_US:en', 07:24:37 INFO - 'LOGNAME': 'cltbld', 07:24:37 INFO - 'MAIL': '/var/mail/cltbld', 07:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:37 INFO - 'MOZ_NO_REMOTE': '1', 07:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:24:37 INFO - 'NO_EM_RESTART': '1', 07:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:24:37 INFO - 'PWD': '/builds/slave/test-pgo', 07:24:37 INFO - 'SHELL': '/bin/bash', 07:24:37 INFO - 'SHLVL': '1', 07:24:37 INFO - 'TERM': 'linux', 07:24:37 INFO - 'TMOUT': '86400', 07:24:37 INFO - 'USER': 'cltbld', 07:24:37 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:37 INFO - '_': '/tools/buildbot/bin/python'} 07:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:37 INFO - Downloading/unpacking psutil>=0.7.1 07:24:37 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 07:24:37 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 07:24:37 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 07:24:37 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 07:24:37 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 07:24:37 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 07:24:38 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 07:24:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:24:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 07:24:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:39 INFO - Installing collected packages: psutil 07:24:39 INFO - Running setup.py install for psutil 07:24:39 INFO - building 'psutil._psutil_linux' extension 07:24:39 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 07:24:39 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 07:24:39 INFO - building 'psutil._psutil_posix' extension 07:24:39 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 07:24:39 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 07:24:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:39 INFO - Successfully installed psutil 07:24:39 INFO - Cleaning up... 07:24:39 INFO - Return code: 0 07:24:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 07:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:39 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 0x7f164c9acc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ed6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ae6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', '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 07:24:39 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 07:24:39 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 07:24:39 INFO - Using env: {'DISPLAY': ':0', 07:24:39 INFO - 'HOME': '/home/cltbld', 07:24:39 INFO - 'LANG': 'en_US.UTF-8', 07:24:39 INFO - 'LANGUAGE': 'en_US:en', 07:24:39 INFO - 'LOGNAME': 'cltbld', 07:24:39 INFO - 'MAIL': '/var/mail/cltbld', 07:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:39 INFO - 'MOZ_NO_REMOTE': '1', 07:24:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:24:39 INFO - 'NO_EM_RESTART': '1', 07:24:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:24:39 INFO - 'PWD': '/builds/slave/test-pgo', 07:24:39 INFO - 'SHELL': '/bin/bash', 07:24:39 INFO - 'SHLVL': '1', 07:24:39 INFO - 'TERM': 'linux', 07:24:39 INFO - 'TMOUT': '86400', 07:24:39 INFO - 'USER': 'cltbld', 07:24:39 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:39 INFO - '_': '/tools/buildbot/bin/python'} 07:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:24:39 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 07:24:39 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 07:24:39 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 07:24:39 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 07:24:39 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 07:24:39 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 07:24:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:24:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:24:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:24:41 INFO - Installing collected packages: mozsystemmonitor 07:24:41 INFO - Running setup.py install for mozsystemmonitor 07:24:41 INFO - Successfully installed mozsystemmonitor 07:24:41 INFO - Cleaning up... 07:24:41 INFO - Return code: 0 07:24:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 07:24: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')]} 07:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24: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')]} 07:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:41 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 0x7f164c9acc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ed6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ae6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', '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 07:24:41 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 07:24:41 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 07:24:41 INFO - Using env: {'DISPLAY': ':0', 07:24:41 INFO - 'HOME': '/home/cltbld', 07:24:41 INFO - 'LANG': 'en_US.UTF-8', 07:24:41 INFO - 'LANGUAGE': 'en_US:en', 07:24:41 INFO - 'LOGNAME': 'cltbld', 07:24:41 INFO - 'MAIL': '/var/mail/cltbld', 07:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:41 INFO - 'MOZ_NO_REMOTE': '1', 07:24:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:24:41 INFO - 'NO_EM_RESTART': '1', 07:24:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:24:41 INFO - 'PWD': '/builds/slave/test-pgo', 07:24:41 INFO - 'SHELL': '/bin/bash', 07:24:41 INFO - 'SHLVL': '1', 07:24:41 INFO - 'TERM': 'linux', 07:24:41 INFO - 'TMOUT': '86400', 07:24:41 INFO - 'USER': 'cltbld', 07:24:41 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:41 INFO - '_': '/tools/buildbot/bin/python'} 07:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:41 INFO - Downloading/unpacking blobuploader==1.2.4 07:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:43 INFO - Downloading blobuploader-1.2.4.tar.gz 07:24:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:24:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:24:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:24:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 07:24:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:44 INFO - Downloading docopt-0.6.1.tar.gz 07:24:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:24:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 07:24:44 INFO - Installing collected packages: blobuploader, requests, docopt 07:24:44 INFO - Running setup.py install for blobuploader 07:24:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:24:44 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 07:24:44 INFO - Running setup.py install for requests 07:24:44 INFO - Running setup.py install for docopt 07:24:44 INFO - Successfully installed blobuploader requests docopt 07:24:44 INFO - Cleaning up... 07:24:44 INFO - Return code: 0 07:24:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:24:44 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')]} 07:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:44 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')]} 07:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:44 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 0x7f164c9acc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ed6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ae6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', '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 07:24:44 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 07:24:44 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 07:24:44 INFO - Using env: {'DISPLAY': ':0', 07:24:44 INFO - 'HOME': '/home/cltbld', 07:24:44 INFO - 'LANG': 'en_US.UTF-8', 07:24:44 INFO - 'LANGUAGE': 'en_US:en', 07:24:44 INFO - 'LOGNAME': 'cltbld', 07:24:44 INFO - 'MAIL': '/var/mail/cltbld', 07:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:44 INFO - 'MOZ_NO_REMOTE': '1', 07:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:24:44 INFO - 'NO_EM_RESTART': '1', 07:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:24:44 INFO - 'PWD': '/builds/slave/test-pgo', 07:24:44 INFO - 'SHELL': '/bin/bash', 07:24:44 INFO - 'SHLVL': '1', 07:24:44 INFO - 'TERM': 'linux', 07:24:44 INFO - 'TMOUT': '86400', 07:24:44 INFO - 'USER': 'cltbld', 07:24:44 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:44 INFO - '_': '/tools/buildbot/bin/python'} 07:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:24:45 INFO - Running setup.py (path:/tmp/pip-nVcPxB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:24:45 INFO - Running setup.py (path:/tmp/pip-kDK7_b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:24:45 INFO - Running setup.py (path:/tmp/pip-asOYGI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:24:45 INFO - Running setup.py (path:/tmp/pip-VAQ4wo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:24:45 INFO - Running setup.py (path:/tmp/pip-cGwgHr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:24:45 INFO - Running setup.py (path:/tmp/pip-FN0Xx4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:24:45 INFO - Running setup.py (path:/tmp/pip-zeiyTS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:24:45 INFO - Running setup.py (path:/tmp/pip-cUh2uU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:24:45 INFO - Running setup.py (path:/tmp/pip-IKXeAI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:24:45 INFO - Running setup.py (path:/tmp/pip-OaQpjk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:24:45 INFO - Running setup.py (path:/tmp/pip-9UfaC1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:24:45 INFO - Running setup.py (path:/tmp/pip-gng2io-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:24:45 INFO - Running setup.py (path:/tmp/pip-NPvvUB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:24:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:24:45 INFO - Running setup.py (path:/tmp/pip-4CqAn7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:24:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:24:46 INFO - Running setup.py (path:/tmp/pip-TZ0kbd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:24:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:24:46 INFO - Running setup.py (path:/tmp/pip-urANUT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:24:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:24:46 INFO - Running setup.py (path:/tmp/pip-0XyErB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:24:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:24:46 INFO - Running setup.py install for manifestparser 07:24:46 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 07:24:46 INFO - Running setup.py install for mozcrash 07:24:46 INFO - Running setup.py install for mozdebug 07:24:46 INFO - Running setup.py install for mozdevice 07:24:46 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 07:24:46 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 07:24:46 INFO - Running setup.py install for mozfile 07:24:46 INFO - Running setup.py install for mozhttpd 07:24:46 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 07:24:46 INFO - Running setup.py install for mozinfo 07:24:46 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 07:24:46 INFO - Running setup.py install for mozInstall 07:24:47 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Running setup.py install for mozleak 07:24:47 INFO - Running setup.py install for mozlog 07:24:47 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Running setup.py install for moznetwork 07:24:47 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Running setup.py install for mozprocess 07:24:47 INFO - Running setup.py install for mozprofile 07:24:47 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Running setup.py install for mozrunner 07:24:47 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Running setup.py install for mozscreenshot 07:24:47 INFO - Running setup.py install for moztest 07:24:47 INFO - Running setup.py install for mozversion 07:24:47 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 07:24:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:24:47 INFO - Cleaning up... 07:24:48 INFO - Return code: 0 07:24:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:24: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')]} 07:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24: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')]} 07:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:48 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 0x7f164c9acc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ed6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ae6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', '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 07:24:48 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 07:24:48 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 07:24:48 INFO - Using env: {'DISPLAY': ':0', 07:24:48 INFO - 'HOME': '/home/cltbld', 07:24:48 INFO - 'LANG': 'en_US.UTF-8', 07:24:48 INFO - 'LANGUAGE': 'en_US:en', 07:24:48 INFO - 'LOGNAME': 'cltbld', 07:24:48 INFO - 'MAIL': '/var/mail/cltbld', 07:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:48 INFO - 'MOZ_NO_REMOTE': '1', 07:24:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:24:48 INFO - 'NO_EM_RESTART': '1', 07:24:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:24:48 INFO - 'PWD': '/builds/slave/test-pgo', 07:24:48 INFO - 'SHELL': '/bin/bash', 07:24:48 INFO - 'SHLVL': '1', 07:24:48 INFO - 'TERM': 'linux', 07:24:48 INFO - 'TMOUT': '86400', 07:24:48 INFO - 'USER': 'cltbld', 07:24:48 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:48 INFO - '_': '/tools/buildbot/bin/python'} 07:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:24:48 INFO - Running setup.py (path:/tmp/pip-mRhFl9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:24:48 INFO - Running setup.py (path:/tmp/pip-4mN6bs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:24:48 INFO - Running setup.py (path:/tmp/pip-lJp8e2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:24:48 INFO - Running setup.py (path:/tmp/pip-29z7wS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 07:24:48 INFO - Running setup.py (path:/tmp/pip-88Y9EZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:24:48 INFO - Running setup.py (path:/tmp/pip-kCZKl6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:24:48 INFO - Running setup.py (path:/tmp/pip-RwA9s2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:24:48 INFO - Running setup.py (path:/tmp/pip-LJ4V_u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 07:24:48 INFO - Running setup.py (path:/tmp/pip-AgD5ep-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 07:24:48 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)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 07:24:48 INFO - Running setup.py (path:/tmp/pip-HrZt6d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 07:24:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 07:24:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:24:48 INFO - Running setup.py (path:/tmp/pip-yxJg33-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 07:24:49 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)) 07:24:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:24:49 INFO - Running setup.py (path:/tmp/pip-2cTmv6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 07:24:49 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)) 07:24:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:24:49 INFO - Running setup.py (path:/tmp/pip-0iDzTW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 07:24:49 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)) 07:24:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:24:49 INFO - Running setup.py (path:/tmp/pip-aQav9K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 07:24:49 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)) 07:24:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:24:49 INFO - Running setup.py (path:/tmp/pip-k_pJXK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 07:24:49 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)) 07:24:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 07:24:49 INFO - Running setup.py (path:/tmp/pip-KZfHT0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 07:24:49 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)) 07:24:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 07:24:49 INFO - Running setup.py (path:/tmp/pip-OHUq4W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 07:24:49 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)) 07:24:49 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)) 07:24:49 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)) 07:24:49 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)) 07:24:49 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)) 07:24:49 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)) 07:24:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 07:24:49 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 07:24:49 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 07:24:49 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 07:24:49 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 07:24:49 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 07:24:49 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 07:24:51 INFO - Downloading blessings-1.5.1.tar.gz 07:24:51 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 07:24:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 07:24:51 INFO - Installing collected packages: blessings 07:24:51 INFO - Running setup.py install for blessings 07:24:51 INFO - Successfully installed blessings 07:24:51 INFO - Cleaning up... 07:24:51 INFO - Return code: 0 07:24:51 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 07:24:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:24:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:24:51 INFO - Reading from file tmpfile_stdout 07:24:51 INFO - Current package versions: 07:24:51 INFO - argparse == 1.2.1 07:24:51 INFO - blessings == 1.5.1 07:24:51 INFO - blobuploader == 1.2.4 07:24:51 INFO - docopt == 0.6.1 07:24:51 INFO - manifestparser == 1.1 07:24:51 INFO - mozInstall == 1.12 07:24:51 INFO - mozcrash == 0.16 07:24:51 INFO - mozdebug == 0.1 07:24:51 INFO - mozdevice == 0.47 07:24:51 INFO - mozfile == 1.2 07:24:51 INFO - mozhttpd == 0.7 07:24:51 INFO - mozinfo == 0.9 07:24:51 INFO - mozleak == 0.1 07:24:51 INFO - mozlog == 3.0 07:24:51 INFO - moznetwork == 0.27 07:24:51 INFO - mozprocess == 0.22 07:24:51 INFO - mozprofile == 0.27 07:24:51 INFO - mozrunner == 6.11 07:24:51 INFO - mozscreenshot == 0.1 07:24:51 INFO - mozsystemmonitor == 0.0 07:24:51 INFO - moztest == 0.7 07:24:51 INFO - mozversion == 1.4 07:24:51 INFO - psutil == 3.1.1 07:24:51 INFO - requests == 1.2.3 07:24:51 INFO - wsgiref == 0.1.2 07:24:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 07:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:51 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 0x7f164c9acc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ed6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ae6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, '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': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', '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 07:24:51 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 07:24:51 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 07:24:51 INFO - Using env: {'DISPLAY': ':0', 07:24:51 INFO - 'HOME': '/home/cltbld', 07:24:51 INFO - 'LANG': 'en_US.UTF-8', 07:24:51 INFO - 'LANGUAGE': 'en_US:en', 07:24:51 INFO - 'LOGNAME': 'cltbld', 07:24:51 INFO - 'MAIL': '/var/mail/cltbld', 07:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:51 INFO - 'MOZ_NO_REMOTE': '1', 07:24:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:24:51 INFO - 'NO_EM_RESTART': '1', 07:24:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:24:51 INFO - 'PWD': '/builds/slave/test-pgo', 07:24:51 INFO - 'SHELL': '/bin/bash', 07:24:51 INFO - 'SHLVL': '1', 07:24:51 INFO - 'TERM': 'linux', 07:24:51 INFO - 'TMOUT': '86400', 07:24:51 INFO - 'USER': 'cltbld', 07:24:51 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:51 INFO - '_': '/tools/buildbot/bin/python'} 07:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 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)) 07:24:51 INFO - Cleaning up... 07:24:51 INFO - Return code: 0 07:24:51 INFO - Running post-action listener: _resource_record_post_action 07:24:51 INFO - Running post-action listener: _start_resource_monitoring 07:24:51 INFO - Starting resource monitoring. 07:24:51 INFO - ##### 07:24:51 INFO - ##### Running install step. 07:24:51 INFO - ##### 07:24:51 INFO - Running pre-action listener: _resource_record_pre_action 07:24:51 INFO - Running main action method: install 07:24:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 07:24:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 07:24:52 INFO - Reading from file tmpfile_stdout 07:24:52 INFO - Detecting whether we're running mozinstall >=1.0... 07:24:52 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 07:24:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 07:24:52 INFO - Reading from file tmpfile_stdout 07:24:52 INFO - Output received: 07:24:52 INFO - Usage: mozinstall [options] installer 07:24:52 INFO - Options: 07:24:52 INFO - -h, --help show this help message and exit 07:24:52 INFO - -d DEST, --destination=DEST 07:24:52 INFO - Directory to install application into. [default: 07:24:52 INFO - "/builds/slave/test-pgo"] 07:24:52 INFO - --app=APP Application being installed. [default: firefox] 07:24:52 INFO - mkdir: /builds/slave/test-pgo/build/application 07:24:52 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'] 07:24:52 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 07:25:06 INFO - Reading from file tmpfile_stdout 07:25:06 INFO - Output received: 07:25:06 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 07:25:06 INFO - Running post-action listener: _resource_record_post_action 07:25:06 INFO - ##### 07:25:06 INFO - ##### Running run-tests step. 07:25:06 INFO - ##### 07:25:06 INFO - Running pre-action listener: _resource_record_pre_action 07:25:06 INFO - Running main action method: run_tests 07:25:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 07:25:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 07:25:06 INFO - Python 2.7.3 07:25:06 INFO - Return code: 0 07:25:06 INFO - grabbing minidump binary from tooltool 07:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:06 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 0x27e4a90>, '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 0x2836f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28372e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 07:25:06 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 07:25:06 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 07:25:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:25:06 INFO - Return code: 0 07:25:06 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 07:25:06 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 07:25:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 07:25:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 07:25:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 07:25:06 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/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 07:25:06 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/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost 07:25:06 INFO - Using env: {'DISPLAY': ':0', 07:25:06 INFO - 'HOME': '/home/cltbld', 07:25:06 INFO - 'LANG': 'en_US.UTF-8', 07:25:06 INFO - 'LANGUAGE': 'en_US:en', 07:25:06 INFO - 'LOGNAME': 'cltbld', 07:25:06 INFO - 'MAIL': '/var/mail/cltbld', 07:25:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:25:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 07:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:06 INFO - 'MOZ_NO_REMOTE': '1', 07:25:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 07:25:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:25:06 INFO - 'NO_EM_RESTART': '1', 07:25:06 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 07:25:06 INFO - 'PWD': '/builds/slave/test-pgo', 07:25:06 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 07:25:06 INFO - 'SHELL': '/bin/bash', 07:25:06 INFO - 'SHLVL': '1', 07:25:06 INFO - 'TERM': 'linux', 07:25:06 INFO - 'TMOUT': '86400', 07:25:06 INFO - 'USER': 'cltbld', 07:25:06 INFO - 'XDG_SESSION_COOKIE': '70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075', 07:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:06 INFO - '_': '/tools/buildbot/bin/python'} 07:25:06 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/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] with output_timeout 3600 07:25:06 INFO - mozversion INFO | application_buildid: 20151120053001 07:25:06 INFO - mozversion INFO | application_changeset: 3d4f379b47fc12812f5e21657b63c6f426f5a063 07:25:06 INFO - mozversion INFO | application_display_name: Nightly 07:25:06 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:25:06 INFO - mozversion INFO | application_name: Firefox 07:25:06 INFO - mozversion INFO | application_remotingname: firefox 07:25:06 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 07:25:06 INFO - mozversion INFO | application_vendor: Mozilla 07:25:06 INFO - mozversion INFO | application_version: 45.0a1 07:25:06 INFO - mozversion INFO | platform_buildid: 20151120053001 07:25:06 INFO - mozversion INFO | platform_changeset: 3d4f379b47fc12812f5e21657b63c6f426f5a063 07:25:06 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 07:25:06 INFO - mozversion INFO | platform_version: 45.0a1 07:25:06 INFO - 2015-11-20 07:25:06,850 DEBUG : using testdate: 1448033106 07:25:06 INFO - 2015-11-20 07:25:06,850 DEBUG : actual date: 1448033106 07:25:06 INFO - 2015-11-20 07:25:06,863 INFO : starting webserver on 'localhost:40947' 07:25:06 INFO - 2015-11-20 07:25:06,865 INFO : Starting test suite talos-linux64-ix-015.e 07:25:06 INFO - 2015-11-20 07:25:06,865 INFO : Starting test dromaeo_css 07:25:06 INFO - 2015-11-20 07:25:06,865 DEBUG : operating with platform_type : linux_ 07:25:06 INFO - 2015-11-20 07:25:06,866 INFO : Initialising browser for dromaeo_css test... 07:25:06 INFO - 2015-11-20 07:25:06,880 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDy4Xtf/profile http://localhost:40947/getInfo.html 07:25:11 INFO - 2015-11-20 07:25:11,960 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:25:11 INFO - 2015-11-20 07:25:11,960 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:25:11 INFO - 2015-11-20 07:25:11,960 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:25:11 INFO - 2015-11-20 07:25:11,960 DEBUG : BROWSER_OUTPUT: __metrics 07:25:13 INFO - 2015-11-20 07:25:13,034 INFO : Browser initialized. 07:25:13 INFO - 2015-11-20 07:25:13,035 INFO : Running cycle 1/1 for dromaeo_css test... 07:25:13 INFO - 2015-11-20 07:25:13,035 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDy4Xtf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:25:24 INFO - 2015-11-20 07:25:24,830 DEBUG : BROWSER_OUTPUT: RSS: Main: 141565952 07:25:24 INFO - 2015-11-20 07:25:24,831 DEBUG : BROWSER_OUTPUT: 07:26:14 INFO - 2015-11-20 07:26:14,454 DEBUG : BROWSER_OUTPUT: 1448033174452 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] 07:28:23 INFO - 2015-11-20 07:28:23,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40947/tests/dromaeo/cssquery-ext.html) 07:28:24 INFO - 2015-11-20 07:28:24,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 147427328 07:28:24 INFO - 2015-11-20 07:28:24,600 DEBUG : BROWSER_OUTPUT: 07:31:26 INFO - 2015-11-20 07:31:26,650 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/dromaeo/cssquery-ext.html (next: http://localhost:40947/tests/dromaeo/cssquery-jquery.html) 07:31:27 INFO - 2015-11-20 07:31:27,232 DEBUG : BROWSER_OUTPUT: RSS: Main: 140337152 07:31:27 INFO - 2015-11-20 07:31:27,232 DEBUG : BROWSER_OUTPUT: 07:34:28 INFO - 2015-11-20 07:34:28,948 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40947/tests/dromaeo/cssquery-mootools.html) 07:34:29 INFO - 2015-11-20 07:34:29,549 DEBUG : BROWSER_OUTPUT: RSS: Main: 141844480 07:34:29 INFO - 2015-11-20 07:34:29,550 DEBUG : BROWSER_OUTPUT: 07:37:31 INFO - 2015-11-20 07:37:31,170 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40947/tests/dromaeo/cssquery-prototype.html) 07:37:31 INFO - 2015-11-20 07:37:31,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 141438976 07:37:31 INFO - 2015-11-20 07:37:31,772 DEBUG : BROWSER_OUTPUT: 07:40:34 INFO - 2015-11-20 07:40:34,123 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40947/tests/dromaeo/cssquery-yui.html) 07:40:34 INFO - 2015-11-20 07:40:34,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 141475840 07:40:34 INFO - 2015-11-20 07:40:34,696 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,241 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/dromaeo/cssquery-yui.html (next: http://localhost:40947/tests/dromaeo/cssquery-dojo.html) 07:43:37 INFO - 2015-11-20 07:43:37,491 DEBUG : BROWSER_OUTPUT: RSS: Main: 144564224 07:43:37 INFO - 2015-11-20 07:43:37,492 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,493 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:43:37 INFO - 2015-11-20 07:43:37,493 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:43:37 INFO - 2015-11-20 07:43:37,493 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:43:37 INFO - 2015-11-20 07:43:37,494 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:43:37 INFO - 2015-11-20 07:43:37,495 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3211.5985501812274;3261.8695252189914;3271.623763267225;3289.24347400098;3297.3570153820015;5344.198370244456;5387.111126664101;5406.053940560401;5424.240606315119;5431.646942948705;5382.273393091931;5463.426340234278;5503.532199763015;5507.9449205507935;5527.806526771564;4964.03201375731;4970.527799859025;5028.572571331423;5049.924251136217;5165.3026841376495;5040.722760248185;5049.722265275408;5106.744662766865;5127.564157046637;5138.126518491847;4881;4890.682105663121;4982.526659967298;5022.924656130161;5031.547160755513;5002.549770520634;5015.924761128586;5016.3980322361185;5022.974885125587;5063.873403164914;4808.615310775164;4962.3548938638105;4973.229149481836;4974.204127339608;5029.547340739315;5235.607329450307;5290.418054014055;5294.549963253128;5310.654807437504;5332.920006199872;5933.258342707161;5943.940560594382;5948.434898684618;5972.581919265654;5979.760809567612;5771.567132465082;5885;5991.790287339966;5996.610220335708;6006.339302676702;5398.352197736258;5459.017376872202;5459.481349271813;5459.918101228484;5506.311711036121;6041.879162416727;6079.148919151322;6082.391760823971;6086.6652334122045;6090;5572.136318870582;5678.261825962599;5769.163471296639;5852.561057920673;5870.882582348329;5929.644221346733;5935.970320148372;5939.406059394026;5946.07835785454;5987.251593550806;5880.064635825042;5885.205497257901;5894.469497745224;5921.526277897758;5933.732982015819;5734.512566431817;5859.941400586024;5871.3541510433815;5879.147523609053;5902.61632993854;4445.3998710174355;4513.864584062483;4526.977365113154;4564.132814765184;4569.13186494565;4081.7346872453195;4083.448734420815;4098.631123198927;4121.423000779893;4134.441850350164;5452.482014208644;5558.749856256478;5595.244641973281;5693.629914055573;5697.4302569742695;5944.3164036136395;5984.8803023940145;6004.3395226525045;6015.0676645120075;6020.939790602126;5650.74571644277;5691.630044047124;5703.657780533182;5708.4862362387585;5716.5998380113;5046.1673823818655;5098.337216161875;5150.639455238173;5168.7415629218385;5186.662866913639;6092.878142437126;6113.113598528189;6133.539984501091;6136.079588061737;6173.84565385869;5895.734691938787;5913.438223368773;5916.290045194604;5924.851878703067;5926.762929482772;5917.733701983335;5989.221401217814;6019.247594050744;6079.848003799941;6114.908276375769;5868.647881127146;5873.177755114202;5896.9115463268945;5899.026660600953;5908.586398952204;5936.436038576501;5998.970005149947;6022;6024.608400453956;6026.548008899263;5660.9150862737915;5714.571407144564;5787.218725472176;5823.417658234142;5894.20428242199;5788.942110578841;5817.331006934088;5833.358330583547;5843.065109582446;5848.356680765198;5391.514763671288;5734.111212762028;5738.426157384228;5758.856028599151;5762.538996880324;5779.479846813639;5786.1899334094105;5787.421257874179;5833.416658334133;5862.325832529314;5727.398623144676;5747.9137812932;5764.423557644202;5767.221425107557;5780.30636323627 07:43:37 INFO - 2015-11-20 07:43:37,499 DEBUG : BROWSER_OUTPUT: |1;ext.html;553.2117550282137;598.3149294058303;602.996985015075;609.098534169429;615.8860610787002;35452.4682129768;36092.45861312083;36206.818965905164;36404.45393319102;36426.99999999996;41994.37008444876;42548.14903701924;42640.573594264046;42648.36027459591;42745;8635.95682021589;8749.781255468632;8775.210231079202;8804.163604457599;8832.425892316978;8540.957295213546;8673.69642062528;8683.175098365646;8696.434731742449;8715.869261961076;8655.913440865574;8666.86999695005;8743.213110820025;8748.86876696847;8757.693480728176;8703.52130632815;8735.563221838882;8792.296616270683;8795.252403545674;8799.428037177628;8528.061913189544;8693.347998900108;8704;8711.564421778949;8717.651293948235;39862;40190.397144042865;40431.7978410109;40475.39286910699;40667.389989150186;3370.4775759757276;3456.8098754568487;3472.3055388922317;3496.4056110461283;3786.6213378662187;29461.263468413246;29834.254143646584;30435.69564304351;30596;31130;3883.087474443532;3892.708046896466;3916.471276377681;3982.4623675803973;4002.8398864045403;250079;251369;252484;253222;253409;55518.05686800943;56339;56620;56752.43247567554;56971;58564.414355856745;58624.41375586275;58712.412875870694;59164.40835591675;59265.70367148223;14637.560873173821;14844.62888427798;14861.256937153099;14883.925580372028;14944.40222391114;11071.50178241965;11208.21542492034;11236;11238.943805281086;11288.43557822122;19062.714059289123;19489.122989465508;19550.70673939892;19557.31549395764;19595.31416400419;7822.960885195652;7939.722109726132;7940.1265860755275;7946.443748937634;7954.522728636184;938.9671361502315;942.540697242259;951.2342564235719;966.4636126949611;1042.1506472225112;28514.71485285162;28782.856085719857;28954.74789684972;29014.27464313409;29202.269943251165;1015.8984101589782;1063.5692544519482;1137.954481820718;1154.2324354304294;1213.7074016172749;14881;15150.621234469007;15169.772453413208;15273.770893436607;15284.235788210768;335389;335448;335517;337547;339273;45905.31142032872;45998.54001459943;46318.76840615843;46436.30345544819;46791;1319.7492476429447;1338.7456383287144;1344.6705557138564;1347.7035052288677;1357.1382172320648;27281.72718272792;27698.169054928385;28222;28347.716522834507;28941.56587651145;45233.32150017686;45251;45496.31755523603;45550.31674524819;45618.77190614026;215624;217320;218641;218934;219080;30869.382612347177;31540.684593154692;32226.67773322237;32229.194270143435;32277.193070172496;38270.234595308495;38841;39013.41479877747;39633.80183099066;39897.60102399055;35177.296454071286;35937;36094.4585831218;36107.27785444329;36388.636113638524;36286.45570316395;36381.090472737334;36697.44953825641;36947.44578831266;36964.260714785014;35053.29893402067;35290;35701.64298357087;35813.1046723834;35984.28031439409;31003;32535;32848.1787955303;32996.1750956228;33492.832535837166;25284.241472755846;25366.619500707875;25392.746072539776;25426.618600721373;25603 07:43:37 INFO - 2015-11-20 07:43:37,500 DEBUG : BROWSER_OUTPUT: |2;jquery.html;361.8751530721902;363.3151509404771;365.17834871539037;366.8789299531155;366.9394549899267;14327.283635818207;14378.856211437882;14647.633809154777;14651.413943442249;16239.837601623994;15982.84017159828;16259.430919917775;16493;16669.66624672544;16704.49886503404;4864.270359446076;5024.924626130611;5034.622403319748;5034.816818047756;5042.47054059324;3975.3241948868763;4011.3180759270995;5340.465953404668;5904.2029326040865;6089.45194932456;6046.062860256668;6055.485283750886;6056.666883321415;6072.2106126203535;6080.665563394012;5038.093143234217;5159.380874295071;5198.194279886624;5198.2982297389935;5202.635815492917;4948.307236986825;4968.056069346812;4979.427365852901;5115.616328775357;5134.4352121267;12484.188527745668;12646.430910609044;13769.862301377058;19433.51416214592;19437.4168774937;2065.917363305466;2068.079704531484;2086.457521044524;2104.063691657213;2158.4927542027613;9099.49952752605;9343.439393636403;9348.485833279166;9495.477748723817;12604.747905041939;1737.9304827806873;1761.9030953297563;1794.246416505071;1839.0988415676356;1882.3223639489772;60426;61171;62656;65448.01827972632;65712;10455.111315538265;10508.369497830156;10509.264351495322;10514.842277365846;10536.262461627612;13623.318834058457;13634.590962271148;13644.249566273751;13678;13688.178709277476;7662.118856331482;7710.190430004881;7750.496217745828;7772.339351155215;7924.049114106228;7424.96287518559;7461.552306861606;7464.514806537558;7499.550026998398;7501.624918754041;4328.718633288826;4448.977755111268;4538.02432477019;4549.567791059844;4572.314152877095;1775.5827380565556;1840.585868179649;1840.9907950460163;1841.2565277736078;1867.8879267244008;957.4973139102027;971.5190980464715;987.3088837813559;1051.164324362134;1102.167863263231;16859.409920652717;16908.492745217663;17030.829691703173;17044.914775426292;17078.40225592123;904.2449554621833;953.3564843730454;966.3332300712486;999.6151481679606;1009.8586197932295;9299.72100836976;9450.810983780286;9476.241900647932;9535.809283814424;9540.809183816424;484992;493250;493923;502914;543359;81546.18453815505;81851;84213.57893210617;84938;85187;1160.8897154770284;1162.953481860733;1169.5321871251567;1174.049020293565;1174.6123779152858;15065.17141557225;15082.924585377003;15084.245787710352;15140.848591513944;15393.76909346383;32286.515702264016;32853;34651.65348346585;35181.120471988404;35234.647653523134;41475.37786933138;42841.35737963995;43010;43280.35079473873;43648.12703745971;8997.955010224907;9520;9572;9662.323637345318;9770.706878793648;11234.83147752768;15253.847461525687;16629.667406652043;16909.830901690824;17094.40169594058;14044.087134336236;14201.360938757574;14475.638109047359;14492;14677.779833302722;13822.1862477642;14617.488387906373;15086.170260635332;15093.698126037198;15125.016873903161;14626.85373146255;14717.705645887236;15101.47144849925;15510.457134000257;15686.764698529303;9214.493202873906;9971.650992215238;10100.090991810772;10106.595736170471;10151.796964060824;14530.636734081732;14709.55871323862;14914.105153690813;14929.477468288558;15228.695426091637 07:43:37 INFO - 2015-11-20 07:43:37,501 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1544.6833399153174;1670.507200375889;1698.3291599818072;1718.063655307857;1725.8101608823026;7427.702891884325;7726.690932362712;7823.92176078239;7857.292843644071;7887.684492620288;7649.961750191255;7803.804904877381;7848.372130229583;7885.763427097197;7919.56442395668;2245.9438514037156;2291.3698732848434;2297.1615360393503;2310.584094862924;2327.7555856635063;2343.1564636730764;2344.882755862209;2386.6539351794067;2387.606045002581;2388.7491813359607;2193.2652561391874;2242.932712018642;2261.4007288068674;2266.7166604174477;2292.6790249365104;2242.013514053811;2245.854019488736;2249.4826189976234;2251.448395143184;2269.2057779776947;2218.456478162844;2358.610829213195;2358.693369861907;2362.83460157789;2365.8580485170946;7735.4585179037485;7741;7770;7786.649600767979;7809.687612495493;4642.744649044301;4670.883227919295;4697.7181369117625;4715.292706094114;4730.266808644666;2987.8506074696397;3032.3328867649084;3055.8472076396315;3078.0304204175836;3100.937981240385;1019.5208252121491;1019.7450637340664;1036.1503567075001;1052.5000624703205;1092.2463500184847;10533.631322903662;10554.261201715883;10624.787504249949;10630.681079567548;10638;4118.258713431611;4129.938050929239;4151.066010147692;4156.521999969982;4213.9578604214175;8616.09530999236;8638.611262493077;8645.394822362374;8654.524001179996;8662.523561204069;4686.992296656235;4688.5545873142;4695.319178719066;4707.058588282357;4735.976320118378;4269.658427325807;4269.914601707948;4273.786310684453;4295.978520107442;4313.094250207491;3433.296174284266;3443.1908501502126;3448.1724386147143;3463.7229021678204;3480.460528618068;1065.6802959112235;1074.4520294649785;1076.8169411200115;1084.5932775209296;1087.190043417659;1070.7751372211924;1085.1969542538623;1125.0942990892247;1254.4229654358915;1323.8146659467682;7257.818554536073;7339.74310899116;7347.118345798538;7375.225601311892;7388.778336649909;1144.2733863996423;1156.3871148291419;1166.702490864824;1174.747429302704;1184.9170558060853;5823.767049317979;5830.329512106162;5833.970830145822;5870.471657550841;5881.941180588225;9024.142706442877;9094;9221.723348299562;9231.861522077174;9355.812883742286;9122;9150.908490915139;9184.586693598803;9215.400998935047;9269.304802139866;7209.35115839577;7257.963710181521;7262.382697470668;7295.416366690652;7341.632918354061;7075.363217310467;7088.610126443095;7172.13934327884;7195.892061618975;7198.388137008413;7962.641681124479;8170.673173073011;8202.220789025034;8238.83522329562;8340.541270230196;8341.791455213426;8352.248297653241;8382.16178382157;8386.616208325559;8400.83198336042;6702.865942681216;6735.6295403753265;6739.191297044386;6759.797206083826;6777.593344399352;7293.343599076109;7366.631668416665;7367.152777430664;7377.963110184414;7383.2985866342615;6760.425363844129;6922.342377474132;6960.199577048502;6996.930030699627;7059.5058345915295;6454.903176452451;6620.337966203341;6632.038354438676;6689.1972963244725;6753.3921947025;6938.93061069403;6969.825754356181;6985.1617805863625;6987.301269873175;6991.510594258353;6142.600730952474;6236.282827474717;6278.497720182283;6287.962486189727;6292.465140463112;6277.403646653561;6283.403076707706;6298.7480500781285;6308.211473565804;6323.905141422791 07:43:37 INFO - 2015-11-20 07:43:37,503 DEBUG : BROWSER_OUTPUT: |4;prototype.html;319.1049107254152;325.42724804344357;331.88384065577037;338.47367343780445;343.26198672853377;6295.087212354211;6392.625585499129;6395.64823934684;6425.1968503937005;6550.0829883816305;6320.146780184674;6371.904421433671;6400.551961362707;6404.903926441096;6416.454601358879;4780.020095880336;4790.496997815231;4798.448178459487;4815.638827087965;4824.686395384307;4811.951880481203;4824.372831531896;4830.9033819323595;4833.275008748698;4842.515748425147;4663.323818046381;4749.216379297412;4755.667103302771;4770.713757174563;4776.116418462596;4741.217699079648;4744.8102075917095;4753.358296629963;4762.023785124076;4763.571278584944;4080.6327430531096;4095.631393174599;4096.549379568245;4114.218298523301;4115.991582062384;6038.516918646497;6042.96978515109;6045.576809623284;6051.969740151315;6057.697115144269;5439.129739241742;5560.916586251169;5561.054620714488;5566.137248726453;5570.554355651539;6701.765438209688;6729.932700673028;6730.360615741497;6732.629705366203;6743.561668491532;4550.362949187116;4565.429321334833;4567.109413664337;4568.428946381702;4573.542645735433;6736.696848641823;6879.484038697067;6932.757353492654;6946.235914049451;7011.719531218745;4599;4651.604613607881;4684.110019096361;4688.882777930511;4694.084653492536;4696.412948381452;4700.576948074689;4712.740799256074;4726.338312636233;4731.408573928259;5526.889462210814;5538.723063846872;5548.056830338852;5548.334199896039;5550.944490555043;6615.86768264632;6756.864862702719;6760.797176084725;6770.322967703289;6807.863842723118;1596.4811436283255;1630.983690163107;1635.6401591650056;1642.1871173769061;1645.2925242145993;1066.626680661762;1074.2802322443981;1076.246627360835;1081.983770243447;1083.9403832789271;6374.840628984312;6431.485481161496;6450.6774661266745;6470.514711396664;6475.676216189172;6848.623325717033;6880.931190688029;6883.862322753517;6903.689333979983;6907.792766217021;6384.265809431975;6432.099506069154;6432.35676432353;6447.162558670756;6468.611883287018;3786.6213378662464;3840.519935008124;3848.749831260959;3851.0757418219987;3859.5368555772925;7347.155077166088;7365.705371785069;7366.594837283894;7373.631318434057;7381.520201186906;7030.3321184487395;7226.132864056347;7235.167955685163;7270.454715896329;7320.450966177558;7367.963160184165;7375.336219740142;7377.88933166003;7379.2251813558805;7388.667509962064;7064.434845212268;7096.396806271525;7102.573845569075;7103.2186459488385;7104.751333703295;6898.16963906902;7005.984132300686;7007.789766307019;7027.543209691354;7027.964860175666;7141.143062832493;7241.927580724126;7249.746258880914;7259.346658800733;7266.96366518164;4636.536346365337;4678.719276843401;4707.882302942454;4718.41019872516;4721.913959789199;6825;6830.624315662686;6985.3713165815325;7011.368976792113;7039.225685174524;6807.489438292247;6828.487863410165;6830.7609233676585;6855.760048398282;6871.793846184622;6764.2221144569;6822;6834.316568343126;6840.110785597841;6846.7945961621235;6543.6400997943665;6679.06493090958;6691.5650482718465;6694.263631000684;6711.530192886451;6719.193696756225;6842.55523390978;6850.349216824394;6876.896846547406;6885.793426197222;6661.367170118831;6671.232808227116;6679.599224046573;6683.064370987969;6685.53201275906 07:43:37 INFO - 2015-11-20 07:43:37,504 DEBUG : BROWSER_OUTPUT: |5;yui.html;853.6286715278852;860.1957170046004;862.4437237981502;879.3536750488394;888.6800751729378;13562.118462299955;13830.93084534576;13848.515301964408;13866.514671986482;13866.584002479916;11730.237534560247;11798;11798.58704945325;11813.468393922272;11891.108166887496;804.047204063184;813.6663967773214;815.2418251026543;816.5753808019826;825.1707034430389;823.8434697407473;824.6412810427483;827.6854795177854;829.274384913201;830.149097175394;854.0520022774715;861.4701958295666;862.6420035685184;864.0495454999482;866.030046348091;843.4939036578065;845.5476320168711;846.3652260804397;848.5799529233008;850.6214734443175;622.8287221014257;627.2347735762363;631.7315141065029;631.8894193516162;632.1402892066832;13845;13869.930650346785;13872.445102196001;13910.095843770123;13930.860691393058;11652.126090543243;11952.282863028246;11973.880261197364;12087.637370878887;12267.631971040882;10071.446070466121;10307.278490505667;10325.535350909227;10384.480775961247;10405.947970260177;3568.589612194605;3633.0372451300163;3633.418653015531;3639.636036396366;3668.6881615062753;47224.05551888977;47254.32948046222;47332.29001564979;48164.27753583699;48741.51258487405;9354.158125768714;9484.95257523708;9679.225661947028;9736.367136136128;9795.461249631197;18043.909780451275;18075.36736214226;18133.546661333574;18177.272909083753;18230.544236394195;7580.507267027626;7596.050493688289;7677.539347639159;7823.960880195563;7882.960585197037;4284.583957442787;4299.849505267363;4301.07526881722;4317.935230971538;4369.169857727022;2982.328975980387;2987.3129180288656;2992.311768293305;2999.355138645158;3007.248187953012;557.1030640668521;560.4451592922977;560.6720068759821;577.295699246918;581.8167277307634;620.6648409858716;654.1496055128314;685.2256949646867;686.972273640649;726.5096060159372;8470.280026197843;9211.30915181364;9304.581293841793;9453.952730236304;9528.19010384125;534.9968808484092;614.9944840186328;694.4617921111078;733.453577085075;738.8732183420219;3909.9413508797393;4103.9179216416105;4231.386448964883;4292.334688123284;4488.753118578445;51161.23258151131;60078;60702.08946865801;60896;61194.69402653047;46867;47106;47576.524234757904;47710.522894770605;48096;44882;45458.77270613692;45478.545214547434;45893.7705311478;46153.07693846104;8740.431871928304;8951.73144805657;9108.271338292789;9164.266858651425;9288.256939444964;33864;34065.31869362648;34258.48612270768;34838.30323393569;35252.47121293234;39760.2047959045;40773.59226407698;44520.55479445294;44832.10335793331;45068.77465612651;10849.294795838245;10948.124150068135;11105.278156919774;11122.610708625483;11256.155788315746;12135.271883687006;12727.045471589483;12754.170978886343;12798.296093714935;12938.611841644766;11634.592789252336;11940.70148246266;12061.216020958609;12329.938350308192;12406.689832754253;11430.542778288775;11701.707457313294;11854.110941679237;11954.222975506913;12068.577599784317;11552.537898484303;11638.12714046433;11652.592159274385;12037.277763334228;12121.212121212093;9779.168770654574;9826.312158149147;9841.163501102486;10012.254841158267;10368.53341599645;9080.954595226982;9462.337636365664;9697.563609637724;9827.467356664025;10076.798464030824 07:43:37 INFO - 2015-11-20 07:43:37,504 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:43:37 INFO - 2015-11-20 07:43:37,504 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:43:37 INFO - 2015-11-20 07:43:37,504 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,307 07:43:37 INFO - 2015-11-20 07:43:37,504 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:43:37 INFO - 2015-11-20 07:43:37,505 DEBUG : BROWSER_OUTPUT: __startTimestamp1448034217492__endTimestamp 07:43:37 INFO - 2015-11-20 07:43:37,505 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,505 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:43:37 INFO - 2015-11-20 07:43:37,505 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:43:37 INFO - 2015-11-20 07:43:37,505 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,505 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5491.47 Median:5734.51 stddev:619.01 (10.8%) stddev-sans-first:594.51 07:43:37 INFO - 2015-11-20 07:43:37,506 DEBUG : BROWSER_OUTPUT: Values: 3211.6 3261.9 3271.6 3289.2 3297.4 5344.2 5387.1 5406.1 5424.2 5431.6 5382.3 5463.4 5503.5 5507.9 5527.8 4964.0 4970.5 5028.6 5049.9 5165.3 5040.7 5049.7 5106.7 5127.6 5138.1 4881.0 4890.7 4982.5 5022.9 5031.5 5002.5 5015.9 5016.4 5023.0 5063.9 4808.6 4962.4 4973.2 4974.2 5029.5 5235.6 5290.4 5294.5 5310.7 5332.9 5933.3 5943.9 5948.4 5972.6 5979.8 5771.6 5885.0 5991.8 5996.6 6006.3 5398.4 5459.0 5459.5 5459.9 5506.3 6041.9 6079.1 6082.4 6086.7 6090.0 5572.1 5678.3 5769.2 5852.6 5870.9 5929.6 5936.0 5939.4 5946.1 5987.3 5880.1 5885.2 5894.5 5921.5 5933.7 5734.5 5859.9 5871.4 5879.1 5902.6 4445.4 4513.9 4527.0 4564.1 4569.1 4081.7 4083.4 4098.6 4121.4 4134.4 5452.5 5558.7 5595.2 5693.6 5697.4 5944.3 5984.9 6004.3 6015.1 6020.9 5650.7 5691.6 5703.7 5708.5 5716.6 5046.2 5098.3 5150.6 5168.7 5186.7 6092.9 6113.1 6133.5 6136.1 6173.8 5895.7 5913.4 5916.3 5924.9 5926.8 5917.7 5989.2 6019.2 6079.8 6114.9 5868.6 5873.2 5896.9 5899.0 5908.6 5936.4 5999.0 6022.0 6024.6 6026.5 5660.9 5714.6 5787.2 5823.4 5894.2 5788.9 5817.3 5833.4 5843.1 5848.4 5391.5 5734.1 5738.4 5758.9 5762.5 5779.5 5786.2 5787.4 5833.4 5862.3 5727.4 5747.9 5764.4 5767.2 5780.3 07:43:37 INFO - 2015-11-20 07:43:37,506 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,506 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43907.28 Median:36157.05 stddev:71437.10 (197.6%) stddev-sans-first:71563.33 07:43:37 INFO - 2015-11-20 07:43:37,506 DEBUG : BROWSER_OUTPUT: Values: 553.2 598.3 603.0 609.1 615.9 35452.5 36092.5 36206.8 36404.5 36427.0 41994.4 42548.1 42640.6 42648.4 42745.0 8636.0 8749.8 8775.2 8804.2 8832.4 8541.0 8673.7 8683.2 8696.4 8715.9 8655.9 8666.9 8743.2 8748.9 8757.7 8703.5 8735.6 8792.3 8795.3 8799.4 8528.1 8693.3 8704.0 8711.6 8717.7 39862.0 40190.4 40431.8 40475.4 40667.4 3370.5 3456.8 3472.3 3496.4 3786.6 29461.3 29834.3 30435.7 30596.0 31130.0 3883.1 3892.7 3916.5 3982.5 4002.8 250079.0 251369.0 252484.0 253222.0 253409.0 55518.1 56339.0 56620.0 56752.4 56971.0 58564.4 58624.4 58712.4 59164.4 59265.7 14637.6 14844.6 14861.3 14883.9 14944.4 11071.5 11208.2 11236.0 11238.9 11288.4 19062.7 19489.1 19550.7 19557.3 19595.3 7823.0 7939.7 7940.1 7946.4 7954.5 939.0 942.5 951.2 966.5 1042.2 28514.7 28782.9 28954.7 29014.3 29202.3 1015.9 1063.6 1138.0 1154.2 1213.7 14881.0 15150.6 15169.8 15273.8 15284.2 335389.0 335448.0 335517.0 337547.0 339273.0 45905.3 45998.5 46318.8 46436.3 46791.0 1319.7 1338.7 1344.7 1347.7 1357.1 27281.7 27698.2 28222.0 28347.7 28941.6 45233.3 45251.0 45496.3 45550.3 45618.8 215624.0 217320.0 218641.0 218934.0 219080.0 30869.4 31540.7 32226.7 32229.2 32277.2 38270.2 38841.0 39013.4 39633.8 39897.6 35177.3 35937.0 36094.5 36107.3 36388.6 36286.5 36381.1 36697.4 36947.4 36964.3 35053.3 35290.0 35701.6 35813.1 35984.3 31003.0 32535.0 32848.2 32996.2 33492.8 25284.2 25366.6 25392.7 25426.6 25603.0 07:43:37 INFO - 2015-11-20 07:43:37,506 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,506 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27750.28 Median:35207.88 stddev:82508.38 (234.3%) stddev-sans-first:82714.21 07:43:37 INFO - 2015-11-20 07:43:37,506 DEBUG : BROWSER_OUTPUT: Values: 361.9 363.3 365.2 366.9 366.9 14327.3 14378.9 14647.6 14651.4 16239.8 15982.8 16259.4 16493.0 16669.7 16704.5 4864.3 5024.9 5034.6 5034.8 5042.5 3975.3 4011.3 5340.5 5904.2 6089.5 6046.1 6055.5 6056.7 6072.2 6080.7 5038.1 5159.4 5198.2 5198.3 5202.6 4948.3 4968.1 4979.4 5115.6 5134.4 12484.2 12646.4 13769.9 19433.5 19437.4 2065.9 2068.1 2086.5 2104.1 2158.5 9099.5 9343.4 9348.5 9495.5 12604.7 1737.9 1761.9 1794.2 1839.1 1882.3 60426.0 61171.0 62656.0 65448.0 65712.0 10455.1 10508.4 10509.3 10514.8 10536.3 13623.3 13634.6 13644.2 13678.0 13688.2 7662.1 7710.2 7750.5 7772.3 7924.0 7425.0 7461.6 7464.5 7499.6 7501.6 4328.7 4449.0 4538.0 4549.6 4572.3 1775.6 1840.6 1841.0 1841.3 1867.9 957.5 971.5 987.3 1051.2 1102.2 16859.4 16908.5 17030.8 17044.9 17078.4 904.2 953.4 966.3 999.6 1009.9 9299.7 9450.8 9476.2 9535.8 9540.8 484992.0 493250.0 493923.0 502914.0 543359.0 81546.2 81851.0 84213.6 84938.0 85187.0 1160.9 1163.0 1169.5 1174.0 1174.6 15065.2 15082.9 15084.2 15140.8 15393.8 32286.5 32853.0 34651.7 35181.1 35234.6 41475.4 42841.4 43010.0 43280.4 43648.1 8998.0 9520.0 9572.0 9662.3 9770.7 11234.8 15253.8 16629.7 16909.8 17094.4 14044.1 14201.4 14475.6 14492.0 14677.8 13822.2 14617.5 15086.2 15093.7 15125.0 14626.9 14717.7 15101.5 15510.5 15686.8 9214.5 9971.7 10100.1 10106.6 10151.8 14530.6 14709.6 14914.1 14929.5 15228.7 07:43:37 INFO - 2015-11-20 07:43:37,507 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,508 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5396.47 Median:6189.44 stddev:2758.71 (44.6%) stddev-sans-first:2751.26 07:43:37 INFO - 2015-11-20 07:43:37,508 DEBUG : BROWSER_OUTPUT: Values: 1544.7 1670.5 1698.3 1718.1 1725.8 7427.7 7726.7 7823.9 7857.3 7887.7 7650.0 7803.8 7848.4 7885.8 7919.6 2245.9 2291.4 2297.2 2310.6 2327.8 2343.2 2344.9 2386.7 2387.6 2388.7 2193.3 2242.9 2261.4 2266.7 2292.7 2242.0 2245.9 2249.5 2251.4 2269.2 2218.5 2358.6 2358.7 2362.8 2365.9 7735.5 7741.0 7770.0 7786.6 7809.7 4642.7 4670.9 4697.7 4715.3 4730.3 2987.9 3032.3 3055.8 3078.0 3100.9 1019.5 1019.7 1036.2 1052.5 1092.2 10533.6 10554.3 10624.8 10630.7 10638.0 4118.3 4129.9 4151.1 4156.5 4214.0 8616.1 8638.6 8645.4 8654.5 8662.5 4687.0 4688.6 4695.3 4707.1 4736.0 4269.7 4269.9 4273.8 4296.0 4313.1 3433.3 3443.2 3448.2 3463.7 3480.5 1065.7 1074.5 1076.8 1084.6 1087.2 1070.8 1085.2 1125.1 1254.4 1323.8 7257.8 7339.7 7347.1 7375.2 7388.8 1144.3 1156.4 1166.7 1174.7 1184.9 5823.8 5830.3 5834.0 5870.5 5881.9 9024.1 9094.0 9221.7 9231.9 9355.8 9122.0 9150.9 9184.6 9215.4 9269.3 7209.4 7258.0 7262.4 7295.4 7341.6 7075.4 7088.6 7172.1 7195.9 7198.4 7962.6 8170.7 8202.2 8238.8 8340.5 8341.8 8352.2 8382.2 8386.6 8400.8 6702.9 6735.6 6739.2 6759.8 6777.6 7293.3 7366.6 7367.2 7378.0 7383.3 6760.4 6922.3 6960.2 6996.9 7059.5 6454.9 6620.3 6632.0 6689.2 6753.4 6938.9 6969.8 6985.2 6987.3 6991.5 6142.6 6236.3 6278.5 6288.0 6292.5 6277.4 6283.4 6298.7 6308.2 6323.9 07:43:37 INFO - 2015-11-20 07:43:37,508 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,508 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5634.61 Median:6428.34 stddev:1742.99 (27.1%) stddev-sans-first:1701.61 07:43:37 INFO - 2015-11-20 07:43:37,508 DEBUG : BROWSER_OUTPUT: Values: 319.1 325.4 331.9 338.5 343.3 6295.1 6392.6 6395.6 6425.2 6550.1 6320.1 6371.9 6400.6 6404.9 6416.5 4780.0 4790.5 4798.4 4815.6 4824.7 4812.0 4824.4 4830.9 4833.3 4842.5 4663.3 4749.2 4755.7 4770.7 4776.1 4741.2 4744.8 4753.4 4762.0 4763.6 4080.6 4095.6 4096.5 4114.2 4116.0 6038.5 6043.0 6045.6 6052.0 6057.7 5439.1 5560.9 5561.1 5566.1 5570.6 6701.8 6729.9 6730.4 6732.6 6743.6 4550.4 4565.4 4567.1 4568.4 4573.5 6736.7 6879.5 6932.8 6946.2 7011.7 4599.0 4651.6 4684.1 4688.9 4694.1 4696.4 4700.6 4712.7 4726.3 4731.4 5526.9 5538.7 5548.1 5548.3 5550.9 6615.9 6756.9 6760.8 6770.3 6807.9 1596.5 1631.0 1635.6 1642.2 1645.3 1066.6 1074.3 1076.2 1082.0 1083.9 6374.8 6431.5 6450.7 6470.5 6475.7 6848.6 6880.9 6883.9 6903.7 6907.8 6384.3 6432.1 6432.4 6447.2 6468.6 3786.6 3840.5 3848.7 3851.1 3859.5 7347.2 7365.7 7366.6 7373.6 7381.5 7030.3 7226.1 7235.2 7270.5 7320.5 7368.0 7375.3 7377.9 7379.2 7388.7 7064.4 7096.4 7102.6 7103.2 7104.8 6898.2 7006.0 7007.8 7027.5 7028.0 7141.1 7241.9 7249.7 7259.3 7267.0 4636.5 4678.7 4707.9 4718.4 4721.9 6825.0 6830.6 6985.4 7011.4 7039.2 6807.5 6828.5 6830.8 6855.8 6871.8 6764.2 6822.0 6834.3 6840.1 6846.8 6543.6 6679.1 6691.6 6694.3 6711.5 6719.2 6842.6 6850.3 6876.9 6885.8 6661.4 6671.2 6679.6 6683.1 6685.5 07:43:37 INFO - 2015-11-20 07:43:37,509 DEBUG : BROWSER_OUTPUT: 07:43:37 INFO - 2015-11-20 07:43:37,509 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13705.74 Median:45686.16 stddev:15628.93 (34.2%) stddev-sans-first:15642.98 07:43:37 INFO - 2015-11-20 07:43:37,510 DEBUG : BROWSER_OUTPUT: Values: 853.6 860.2 862.4 879.4 888.7 13562.1 13830.9 13848.5 13866.5 13866.6 11730.2 11798.0 11798.6 11813.5 11891.1 804.0 813.7 815.2 816.6 825.2 823.8 824.6 827.7 829.3 830.1 854.1 861.5 862.6 864.0 866.0 843.5 845.5 846.4 848.6 850.6 622.8 627.2 631.7 631.9 632.1 13845.0 13869.9 13872.4 13910.1 13930.9 11652.1 11952.3 11973.9 12087.6 12267.6 10071.4 10307.3 10325.5 10384.5 10405.9 3568.6 3633.0 3633.4 3639.6 3668.7 47224.1 47254.3 47332.3 48164.3 48741.5 9354.2 9485.0 9679.2 9736.4 9795.5 18043.9 18075.4 18133.5 18177.3 18230.5 7580.5 7596.1 7677.5 7824.0 7883.0 4284.6 4299.8 4301.1 4317.9 4369.2 2982.3 2987.3 2992.3 2999.4 3007.2 557.1 560.4 560.7 577.3 581.8 620.7 654.1 685.2 687.0 726.5 8470.3 9211.3 9304.6 9454.0 9528.2 535.0 615.0 694.5 733.5 738.9 3909.9 4103.9 4231.4 4292.3 4488.8 51161.2 60078.0 60702.1 60896.0 61194.7 46867.0 47106.0 47576.5 47710.5 48096.0 44882.0 45458.8 45478.5 45893.8 46153.1 8740.4 8951.7 9108.3 9164.3 9288.3 33864.0 34065.3 34258.5 34838.3 35252.5 39760.2 40773.6 44520.6 44832.1 45068.8 10849.3 10948.1 11105.3 11122.6 11256.2 12135.3 12727.0 12754.2 12798.3 12938.6 11634.6 11940.7 12061.2 12329.9 12406.7 11430.5 11701.7 11854.1 11954.2 12068.6 11552.5 11638.1 11652.6 12037.3 12121.2 9779.2 9826.3 9841.2 10012.3 10368.5 9081.0 9462.3 9697.6 9827.5 10076.8 07:43:37 INFO - 2015-11-20 07:43:37,510 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:43:37 INFO - 2015-11-20 07:43:37,510 DEBUG : BROWSER_OUTPUT: 07:43:38 INFO - 2015-11-20 07:43:38,174 INFO : Browser exited with error code: 0 07:43:38 INFO - 2015-11-20 07:43:38,193 INFO : Completed test dromaeo_css (00:18:31) 07:43:38 INFO - 2015-11-20 07:43:38,193 INFO : Starting test kraken 07:43:38 INFO - 2015-11-20 07:43:38,193 DEBUG : operating with platform_type : linux_ 07:43:38 INFO - 2015-11-20 07:43:38,193 INFO : Initialising browser for kraken test... 07:43:38 INFO - 2015-11-20 07:43:38,199 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpr4UjzI/profile http://localhost:40947/getInfo.html 07:43:42 INFO - 2015-11-20 07:43:42,870 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:43:42 INFO - 2015-11-20 07:43:42,870 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:43:42 INFO - 2015-11-20 07:43:42,870 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:43:42 INFO - 2015-11-20 07:43:42,870 DEBUG : BROWSER_OUTPUT: __metrics 07:43:43 INFO - 2015-11-20 07:43:43,950 INFO : Browser initialized. 07:43:43 INFO - 2015-11-20 07:43:43,950 INFO : Running cycle 1/1 for kraken test... 07:43:43 INFO - 2015-11-20 07:43:43,951 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpr4UjzI/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:43:55 INFO - 2015-11-20 07:43:55,722 DEBUG : BROWSER_OUTPUT: RSS: Main: 141938688 07:43:55 INFO - 2015-11-20 07:43:55,723 DEBUG : BROWSER_OUTPUT: 07:43:58 INFO - 2015-11-20 07:43:58,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40947/tests/kraken/driver.html?testName=audio-beat-detection) 07:43:58 INFO - 2015-11-20 07:43:58,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 142602240 07:43:58 INFO - 2015-11-20 07:43:58,787 DEBUG : BROWSER_OUTPUT: 07:44:01 INFO - 2015-11-20 07:44:01,448 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40947/tests/kraken/driver.html?testName=audio-dft) 07:44:02 INFO - 2015-11-20 07:44:02,034 DEBUG : BROWSER_OUTPUT: RSS: Main: 142626816 07:44:02 INFO - 2015-11-20 07:44:02,034 DEBUG : BROWSER_OUTPUT: 07:44:04 INFO - 2015-11-20 07:44:04,898 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40947/tests/kraken/driver.html?testName=audio-fft) 07:44:05 INFO - 2015-11-20 07:44:05,479 DEBUG : BROWSER_OUTPUT: RSS: Main: 136425472 07:44:05 INFO - 2015-11-20 07:44:05,480 DEBUG : BROWSER_OUTPUT: 07:44:07 INFO - 2015-11-20 07:44:07,443 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40947/tests/kraken/driver.html?testName=audio-oscillator) 07:44:08 INFO - 2015-11-20 07:44:08,025 DEBUG : BROWSER_OUTPUT: RSS: Main: 137424896 07:44:08 INFO - 2015-11-20 07:44:08,025 DEBUG : BROWSER_OUTPUT: 07:44:10 INFO - 2015-11-20 07:44:10,231 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40947/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:44:10 INFO - 2015-11-20 07:44:10,800 DEBUG : BROWSER_OUTPUT: RSS: Main: 137588736 07:44:10 INFO - 2015-11-20 07:44:10,801 DEBUG : BROWSER_OUTPUT: 07:44:13 INFO - 2015-11-20 07:44:13,841 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40947/tests/kraken/driver.html?testName=imaging-darkroom) 07:44:14 INFO - 2015-11-20 07:44:14,443 DEBUG : BROWSER_OUTPUT: RSS: Main: 137633792 07:44:14 INFO - 2015-11-20 07:44:14,443 DEBUG : BROWSER_OUTPUT: 07:44:17 INFO - 2015-11-20 07:44:17,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40947/tests/kraken/driver.html?testName=imaging-desaturate) 07:44:18 INFO - 2015-11-20 07:44:18,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 139087872 07:44:18 INFO - 2015-11-20 07:44:18,395 DEBUG : BROWSER_OUTPUT: 07:44:21 INFO - 2015-11-20 07:44:21,233 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40947/tests/kraken/driver.html?testName=json-parse-financial) 07:44:21 INFO - 2015-11-20 07:44:21,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 139333632 07:44:21 INFO - 2015-11-20 07:44:21,819 DEBUG : BROWSER_OUTPUT: 07:44:23 INFO - 2015-11-20 07:44:23,429 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40947/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:44:23 INFO - 2015-11-20 07:44:23,996 DEBUG : BROWSER_OUTPUT: RSS: Main: 139780096 07:44:23 INFO - 2015-11-20 07:44:23,997 DEBUG : BROWSER_OUTPUT: 07:44:25 INFO - 2015-11-20 07:44:25,826 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:44:26 INFO - 2015-11-20 07:44:26,427 DEBUG : BROWSER_OUTPUT: RSS: Main: 139792384 07:44:26 INFO - 2015-11-20 07:44:26,428 DEBUG : BROWSER_OUTPUT: 07:44:28 INFO - 2015-11-20 07:44:28,487 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:44:29 INFO - 2015-11-20 07:44:29,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 140152832 07:44:29 INFO - 2015-11-20 07:44:29,078 DEBUG : BROWSER_OUTPUT: 07:44:31 INFO - 2015-11-20 07:44:31,539 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:44:32 INFO - 2015-11-20 07:44:32,132 DEBUG : BROWSER_OUTPUT: RSS: Main: 140435456 07:44:32 INFO - 2015-11-20 07:44:32,132 DEBUG : BROWSER_OUTPUT: 07:44:34 INFO - 2015-11-20 07:44:34,732 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:44:35 INFO - 2015-11-20 07:44:35,318 DEBUG : BROWSER_OUTPUT: RSS: Main: 139350016 07:44:35 INFO - 2015-11-20 07:44:35,318 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,008 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40947/tests/kraken/driver.html?testName=ai-astar) 07:44:37 INFO - 2015-11-20 07:44:37,258 DEBUG : BROWSER_OUTPUT: RSS: Main: 139431936 07:44:37 INFO - 2015-11-20 07:44:37,258 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,258 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:44:37 INFO - 2015-11-20 07:44:37,258 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:44:37 INFO - 2015-11-20 07:44:37,258 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:44:37 INFO - 2015-11-20 07:44:37,258 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:44:37 INFO - 2015-11-20 07:44:37,258 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;107;110;109;109;122;109;110;112;110 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;125;157;171;125;148;154;130;164;136 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;163;163;164;164;166;162;164;169;163 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;75;73;73;72;136;71;73;72;72 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;90;141;90;89;89;129;91;92;133 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;111;111;109;110;110;111;110;110;110 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;147;146;148;149;146;147;147;147 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;97;97;96;97;96;98;96;97;97;96 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;63;65;62;61;64;62;62;61;59 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;67;68;69;69;70;70;68;69;69 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;83;86;85;85;85;93;85;86;94 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;116;114;126;122;121;129;134;116;118 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;142;155;148;160;153;158;150;153;163;142 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;67;68;72;68;66;66;67;68;65 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:44:37 INFO - 2015-11-20 07:44:37,259 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,706 07:44:37 INFO - 2015-11-20 07:44:37,260 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:44:37 INFO - 2015-11-20 07:44:37,260 DEBUG : BROWSER_OUTPUT: __startTimestamp1448034277256__endTimestamp 07:44:37 INFO - 2015-11-20 07:44:37,260 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,261 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:44:37 INFO - 2015-11-20 07:44:37,261 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:44:37 INFO - 2015-11-20 07:44:37,261 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,262 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.90 Median:110.00 stddev:4.12 (3.7%) stddev-sans-first:4.37 07:44:37 INFO - 2015-11-20 07:44:37,262 DEBUG : BROWSER_OUTPUT: Values: 111.0 107.0 110.0 109.0 109.0 122.0 109.0 110.0 112.0 110.0 07:44:37 INFO - 2015-11-20 07:44:37,262 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,263 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.80 Median:151.00 stddev:16.26 (10.8%) stddev-sans-first:17.23 07:44:37 INFO - 2015-11-20 07:44:37,263 DEBUG : BROWSER_OUTPUT: Values: 148.0 125.0 157.0 171.0 125.0 148.0 154.0 130.0 164.0 136.0 07:44:37 INFO - 2015-11-20 07:44:37,263 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,263 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.02 (1.2%) stddev-sans-first:2.11 07:44:37 INFO - 2015-11-20 07:44:37,263 DEBUG : BROWSER_OUTPUT: Values: 163.0 163.0 163.0 164.0 164.0 166.0 162.0 164.0 169.0 163.0 07:44:37 INFO - 2015-11-20 07:44:37,263 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.90 Median:72.50 stddev:20.09 (27.7%) stddev-sans-first:21.15 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: Values: 72.0 75.0 73.0 73.0 72.0 136.0 71.0 73.0 72.0 72.0 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.50 Median:90.00 stddev:21.49 (23.9%) stddev-sans-first:22.31 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: Values: 91.0 90.0 141.0 90.0 89.0 89.0 129.0 91.0 92.0 133.0 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:110.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: Values: 111.0 111.0 111.0 109.0 110.0 110.0 111.0 110.0 110.0 110.0 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,264 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 147.0 146.0 148.0 149.0 146.0 147.0 147.0 147.0 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.70 Median:97.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: Values: 97.0 97.0 96.0 97.0 96.0 98.0 96.0 97.0 97.0 96.0 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:1.66 (2.7%) stddev-sans-first:1.76 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: Values: 62.0 63.0 65.0 62.0 61.0 64.0 62.0 62.0 61.0 59.0 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,265 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.95 (1.4%) stddev-sans-first:0.97 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 68.0 69.0 69.0 70.0 70.0 68.0 69.0 69.0 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:85.50 stddev:3.75 (4.4%) stddev-sans-first:3.86 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: Values: 84.0 83.0 86.0 85.0 85.0 85.0 93.0 85.0 86.0 94.0 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.60 Median:121.50 stddev:6.36 (5.2%) stddev-sans-first:6.72 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: Values: 120.0 116.0 114.0 126.0 122.0 121.0 129.0 134.0 116.0 118.0 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,266 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:154.00 stddev:7.07 (4.6%) stddev-sans-first:6.42 07:44:37 INFO - 2015-11-20 07:44:37,267 DEBUG : BROWSER_OUTPUT: Values: 142.0 155.0 148.0 160.0 153.0 158.0 150.0 153.0 163.0 142.0 07:44:37 INFO - 2015-11-20 07:44:37,267 DEBUG : BROWSER_OUTPUT: 07:44:37 INFO - 2015-11-20 07:44:37,267 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:2.20 (3.2%) stddev-sans-first:2.01 07:44:37 INFO - 2015-11-20 07:44:37,267 DEBUG : BROWSER_OUTPUT: Values: 71.0 67.0 68.0 72.0 68.0 66.0 66.0 67.0 68.0 65.0 07:44:37 INFO - 2015-11-20 07:44:37,267 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:44:37 INFO - 2015-11-20 07:44:37,267 DEBUG : BROWSER_OUTPUT: 07:44:38 INFO - 2015-11-20 07:44:38,234 INFO : Browser exited with error code: 0 07:44:38 INFO - 2015-11-20 07:44:38,285 INFO : Completed test kraken (00:01:00) 07:44:38 INFO - 2015-11-20 07:44:38,285 INFO : Starting test v8_7 07:44:38 INFO - 2015-11-20 07:44:38,285 DEBUG : operating with platform_type : linux_ 07:44:38 INFO - 2015-11-20 07:44:38,285 INFO : Initialising browser for v8_7 test... 07:44:38 INFO - 2015-11-20 07:44:38,291 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpse4rFZ/profile http://localhost:40947/getInfo.html 07:44:43 INFO - 2015-11-20 07:44:43,177 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:44:43 INFO - 2015-11-20 07:44:43,178 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:44:43 INFO - 2015-11-20 07:44:43,178 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:44:43 INFO - 2015-11-20 07:44:43,178 DEBUG : BROWSER_OUTPUT: __metrics 07:44:44 INFO - 2015-11-20 07:44:44,234 INFO : Browser initialized. 07:44:44 INFO - 2015-11-20 07:44:44,235 INFO : Running cycle 1/1 for v8_7 test... 07:44:44 INFO - 2015-11-20 07:44:44,235 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpse4rFZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:44:55 INFO - 2015-11-20 07:44:55,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 141946880 07:44:55 INFO - 2015-11-20 07:44:55,992 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40947/tests/v8_7/run.html (next: http://localhost:40947/tests/v8_7/run.html) 07:45:17 INFO - 2015-11-20 07:45:17,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 138985472 07:45:17 INFO - 2015-11-20 07:45:17,600 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,601 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:45:17 INFO - 2015-11-20 07:45:17,601 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:45:17 INFO - 2015-11-20 07:45:17,601 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:45:17 INFO - 2015-11-20 07:45:17,601 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:45:17 INFO - 2015-11-20 07:45:17,601 DEBUG : BROWSER_OUTPUT: |0;Richards;166.47244880972198 07:45:17 INFO - 2015-11-20 07:45:17,601 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.8268414963363 07:45:17 INFO - 2015-11-20 07:45:17,601 DEBUG : BROWSER_OUTPUT: |2;Encrypt;284.09090909090907 07:45:17 INFO - 2015-11-20 07:45:17,602 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5050.50505050505 07:45:17 INFO - 2015-11-20 07:45:17,602 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1062.6992561105208 07:45:17 INFO - 2015-11-20 07:45:17,602 DEBUG : BROWSER_OUTPUT: |5;Earley;708.7172218284904 07:45:17 INFO - 2015-11-20 07:45:17,602 DEBUG : BROWSER_OUTPUT: |6;Boyer;12156.626506024097 07:45:17 INFO - 2015-11-20 07:45:17,602 DEBUG : BROWSER_OUTPUT: |7;RegExp;24902.439024390245 07:45:17 INFO - 2015-11-20 07:45:17,602 DEBUG : BROWSER_OUTPUT: |8;Splay;622.66500622665 07:45:17 INFO - 2015-11-20 07:45:17,602 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4882.926829268293 07:45:17 INFO - 2015-11-20 07:45:17,603 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:45:17 INFO - 2015-11-20 07:45:17,603 DEBUG : BROWSER_OUTPUT: __startTimestamp1448034317599__endTimestamp 07:45:17 INFO - 2015-11-20 07:45:17,603 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,603 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:45:17 INFO - 2015-11-20 07:45:17,603 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:45:17 INFO - 2015-11-20 07:45:17,603 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,603 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.47 Median:166.47 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: Values: 166.5 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.83 Median:192.83 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: Values: 192.8 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:284.09 Median:284.09 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: Values: 284.1 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5050.51 Median:5050.51 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: Values: 5050.5 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,604 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1062.70 Median:1062.70 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: Values: 1062.7 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:708.72 Median:708.72 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: Values: 708.7 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12156.63 Median:12156.63 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: Values: 12156.6 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24902.44 Median:24902.44 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,605 DEBUG : BROWSER_OUTPUT: Values: 24902.4 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:622.67 Median:622.67 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: Values: 622.7 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4882.93 Median:4882.93 stddev:0.00 (0.0%) 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: Values: 4882.9 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:45:17 INFO - 2015-11-20 07:45:17,606 DEBUG : BROWSER_OUTPUT: 07:45:18 INFO - 2015-11-20 07:45:18,498 INFO : Browser exited with error code: 0 07:45:18 INFO - 2015-11-20 07:45:18,517 INFO : Completed test v8_7 (00:00:40) 07:45:18 INFO - 2015-11-20 07:45:18,678 INFO : Completed test suite (00:20:11) 07:45:18 INFO - 2015-11-20 07:45:18,678 DEBUG : Working with test: dromaeo_css 07:45:18 INFO - 2015-11-20 07:45:18,678 DEBUG : Generating results file: dromaeo_css 07:45:18 INFO - 2015-11-20 07:45:18,679 DEBUG : Working with test: kraken 07:45:18 INFO - 2015-11-20 07:45:18,679 DEBUG : Generating results file: kraken 07:45:18 INFO - 2015-11-20 07:45:18,680 INFO : javascript benchmark 07:45:18 INFO - 2015-11-20 07:45:18,680 DEBUG : Working with test: v8_7 07:45:18 INFO - 2015-11-20 07:45:18,680 DEBUG : Generating results file: v8_7 07:45:18 INFO - 2015-11-20 07:45:18,680 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:45:18 INFO - 2015-11-20 07:45:18,733 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 07:45:18 INFO - 2015-11-20 07:45:18,733 DEBUG : process_Request line: dromaeo_css 7074.36 graph.html#tests=[[72,64,43]] 07:45:18 INFO - 2015-11-20 07:45:18,733 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:45:18 INFO - 2015-11-20 07:45:18,763 DEBUG : process_Request line: kraken 1516.60 graph.html#tests=[[232,64,43]] 07:45:18 INFO - 2015-11-20 07:45:18,763 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:45:18 INFO - 2015-11-20 07:45:18,867 DEBUG : process_Request line: v8_7 21331.10 graph.html#tests=[[230,64,43]] 07:45:18 INFO - 2015-11-20 07:45:18,868 INFO : javascript benchmark 07:45:18 INFO - 2015-11-20 07:45:18,871 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3211.5985501812274, 3261.8695252189914, 3271.623763267225, 3289.24347400098, 3297.3570153820015, 5344.198370244456, 5387.111126664101, 5406.053940560401, 5424.240606315119, 5431.646942948705, 5382.273393091931, 5463.426340234278, 5503.532199763015, 5507.9449205507935, 5527.806526771564, 4964.03201375731, 4970.527799859025, 5028.572571331423, 5049.924251136217, 5165.3026841376495, 5040.722760248185, 5049.722265275408, 5106.744662766865, 5127.564157046637, 5138.126518491847, 4881.0, 4890.682105663121, 4982.526659967298, 5022.924656130161, 5031.547160755513, 5002.549770520634, 5015.924761128586, 5016.3980322361185, 5022.974885125587, 5063.873403164914, 4808.615310775164, 4962.3548938638105, 4973.229149481836, 4974.204127339608, 5029.547340739315, 5235.607329450307, 5290.418054014055, 5294.549963253128, 5310.654807437504, 5332.920006199872, 5933.258342707161, 5943.940560594382, 5948.434898684618, 5972.581919265654, 5979.760809567612, 5771.567132465082, 5885.0, 5991.790287339966, 5996.610220335708, 6006.339302676702, 5398.352197736258, 5459.017376872202, 5459.481349271813, 5459.918101228484, 5506.311711036121, 6041.879162416727, 6079.148919151322, 6082.391760823971, 6086.6652334122045, 6090.0, 5572.136318870582, 5678.261825962599, 5769.163471296639, 5852.561057920673, 5870.882582348329, 5929.644221346733, 5935.970320148372, 5939.406059394026, 5946.07835785454, 5987.251593550806, 5880.064635825042, 5885.205497257901, 5894.469497745224, 5921.526277897758, 5933.732982015819, 5734.512566431817, 5859.941400586024, 5871.3541510433815, 5879.147523609053, 5902.61632993854, 4445.3998710174355, 4513.864584062483, 4526.977365113154, 4564.132814765184, 4569.13186494565, 4081.7346872453195, 4083.448734420815, 4098.631123198927, 4121.423000779893, 4134.441850350164, 5452.482014208644, 5558.749856256478, 5595.244641973281, 5693.629914055573, 5697.4302569742695, 5944.3164036136395, 5984.8803023940145, 6004.3395226525045, 6015.0676645120075, 6020.939790602126, 5650.74571644277, 5691.630044047124, 5703.657780533182, 5708.4862362387585, 5716.5998380113, 5046.1673823818655, 5098.337216161875, 5150.639455238173, 5168.7415629218385, 5186.662866913639, 6092.878142437126, 6113.113598528189, 6133.539984501091, 6136.079588061737, 6173.84565385869, 5895.734691938787, 5913.438223368773, 5916.290045194604, 5924.851878703067, 5926.762929482772, 5917.733701983335, 5989.221401217814, 6019.247594050744, 6079.848003799941, 6114.908276375769, 5868.647881127146, 5873.177755114202, 5896.9115463268945, 5899.026660600953, 5908.586398952204, 5936.436038576501, 5998.970005149947, 6022.0, 6024.608400453956, 6026.548008899263, 5660.9150862737915, 5714.571407144564, 5787.218725472176, 5823.417658234142, 5894.20428242199, 5788.942110578841, 5817.331006934088, 5833.358330583547, 5843.065109582446, 5848.356680765198, 5391.514763671288, 5734.111212762028, 5738.426157384228, 5758.856028599151, 5762.538996880324, 5779.479846813639, 5786.1899334094105, 5787.421257874179, 5833.416658334133, 5862.325832529314, 5727.398623144676, 5747.9137812932, 5764.423557644202, 5767.221425107557, 5780.30636323627], "unit": "ms", "name": "dojo.html", "value": 5450.6760828984525}, {"lowerIsBetter": false, "replicates": [553.2117550282137, 598.3149294058303, 602.996985015075, 609.098534169429, 615.8860610787002, 35452.4682129768, 36092.45861312083, 36206.818965905164, 36404.45393319102, 36426.99999999996, 41994.37008444876, 42548.14903701924, 42640.573594264046, 42648.36027459591, 42745.0, 8635.95682021589, 8749.781255468632, 8775.210231079202, 8804.163604457599, 8832.425892316978, 8540.957295213546, 8673.69642062528, 8683.175098365646, 8696.434731742449, 8715.869261961076, 8655.913440865574, 8666.86999695005, 8743.213110820025, 8748.86876696847, 8757.693480728176, 8703.52130632815, 8735.563221838882, 8792.296616270683, 8795.252403545674, 8799.428037177628, 8528.061913189544, 8693.347998900108, 8704.0, 8711.564421778949, 8717.651293948235, 39862.0, 40190.397144042865, 40431.7978410109, 40475.39286910699, 40667.389989150186, 3370.4775759757276, 3456.8098754568487, 3472.3055388922317, 3496.4056110461283, 3786.6213378662187, 29461.263468413246, 29834.254143646584, 30435.69564304351, 30596.0, 31130.0, 3883.087474443532, 3892.708046896466, 3916.471276377681, 3982.4623675803973, 4002.8398864045403, 250079.0, 251369.0, 252484.0, 253222.0, 253409.0, 55518.05686800943, 56339.0, 56620.0, 56752.43247567554, 56971.0, 58564.414355856745, 58624.41375586275, 58712.412875870694, 59164.40835591675, 59265.70367148223, 14637.560873173821, 14844.62888427798, 14861.256937153099, 14883.925580372028, 14944.40222391114, 11071.50178241965, 11208.21542492034, 11236.0, 11238.943805281086, 11288.43557822122, 19062.714059289123, 19489.122989465508, 19550.70673939892, 19557.31549395764, 19595.31416400419, 7822.960885195652, 7939.722109726132, 7940.1265860755275, 7946.443748937634, 7954.522728636184, 938.9671361502315, 942.540697242259, 951.2342564235719, 966.4636126949611, 1042.1506472225112, 28514.71485285162, 28782.856085719857, 28954.74789684972, 29014.27464313409, 29202.269943251165, 1015.8984101589782, 1063.5692544519482, 1137.954481820718, 1154.2324354304294, 1213.7074016172749, 14881.0, 15150.621234469007, 15169.772453413208, 15273.770893436607, 15284.235788210768, 335389.0, 335448.0, 335517.0, 337547.0, 339273.0, 45905.31142032872, 45998.54001459943, 46318.76840615843, 46436.30345544819, 46791.0, 1319.7492476429447, 1338.7456383287144, 1344.6705557138564, 1347.7035052288677, 1357.1382172320648, 27281.72718272792, 27698.169054928385, 28222.0, 28347.716522834507, 28941.56587651145, 45233.32150017686, 45251.0, 45496.31755523603, 45550.31674524819, 45618.77190614026, 215624.0, 217320.0, 218641.0, 218934.0, 219080.0, 30869.382612347177, 31540.684593154692, 32226.67773322237, 32229.194270143435, 32277.193070172496, 38270.234595308495, 38841.0, 39013.41479877747, 39633.80183099066, 39897.60102399055, 35177.296454071286, 35937.0, 36094.4585831218, 36107.27785444329, 36388.636113638524, 36286.45570316395, 36381.090472737334, 36697.44953825641, 36947.44578831266, 36964.260714785014, 35053.29893402067, 35290.0, 35701.64298357087, 35813.1046723834, 35984.28031439409, 31003.0, 32535.0, 32848.1787955303, 32996.1750956228, 33492.832535837166, 25284.241472755846, 25366.619500707875, 25392.746072539776, 25426.618600721373, 25603.0], "unit": "ms", "name": "ext.html", "value": 18183.189304143783}, {"lowerIsBetter": false, "replicates": [361.8751530721902, 363.3151509404771, 365.17834871539037, 366.8789299531155, 366.9394549899267, 14327.283635818207, 14378.856211437882, 14647.633809154777, 14651.413943442249, 16239.837601623994, 15982.84017159828, 16259.430919917775, 16493.0, 16669.66624672544, 16704.49886503404, 4864.270359446076, 5024.924626130611, 5034.622403319748, 5034.816818047756, 5042.47054059324, 3975.3241948868763, 4011.3180759270995, 5340.465953404668, 5904.2029326040865, 6089.45194932456, 6046.062860256668, 6055.485283750886, 6056.666883321415, 6072.2106126203535, 6080.665563394012, 5038.093143234217, 5159.380874295071, 5198.194279886624, 5198.2982297389935, 5202.635815492917, 4948.307236986825, 4968.056069346812, 4979.427365852901, 5115.616328775357, 5134.4352121267, 12484.188527745668, 12646.430910609044, 13769.862301377058, 19433.51416214592, 19437.4168774937, 2065.917363305466, 2068.079704531484, 2086.457521044524, 2104.063691657213, 2158.4927542027613, 9099.49952752605, 9343.439393636403, 9348.485833279166, 9495.477748723817, 12604.747905041939, 1737.9304827806873, 1761.9030953297563, 1794.246416505071, 1839.0988415676356, 1882.3223639489772, 60426.0, 61171.0, 62656.0, 65448.01827972632, 65712.0, 10455.111315538265, 10508.369497830156, 10509.264351495322, 10514.842277365846, 10536.262461627612, 13623.318834058457, 13634.590962271148, 13644.249566273751, 13678.0, 13688.178709277476, 7662.118856331482, 7710.190430004881, 7750.496217745828, 7772.339351155215, 7924.049114106228, 7424.96287518559, 7461.552306861606, 7464.514806537558, 7499.550026998398, 7501.624918754041, 4328.718633288826, 4448.977755111268, 4538.02432477019, 4549.567791059844, 4572.314152877095, 1775.5827380565556, 1840.585868179649, 1840.9907950460163, 1841.2565277736078, 1867.8879267244008, 957.4973139102027, 971.5190980464715, 987.3088837813559, 1051.164324362134, 1102.167863263231, 16859.409920652717, 16908.492745217663, 17030.829691703173, 17044.914775426292, 17078.40225592123, 904.2449554621833, 953.3564843730454, 966.3332300712486, 999.6151481679606, 1009.8586197932295, 9299.72100836976, 9450.810983780286, 9476.241900647932, 9535.809283814424, 9540.809183816424, 484992.0, 493250.0, 493923.0, 502914.0, 543359.0, 81546.18453815505, 81851.0, 84213.57893210617, 84938.0, 85187.0, 1160.8897154770284, 1162.953481860733, 1169.5321871251567, 1174.049020293565, 1174.6123779152858, 15065.17141557225, 15082.924585377003, 15084.245787710352, 15140.848591513944, 15393.76909346383, 32286.515702264016, 32853.0, 34651.65348346585, 35181.120471988404, 35234.647653523134, 41475.37786933138, 42841.35737963995, 43010.0, 43280.35079473873, 43648.12703745971, 8997.955010224907, 9520.0, 9572.0, 9662.323637345318, 9770.706878793648, 11234.83147752768, 15253.847461525687, 16629.667406652043, 16909.830901690824, 17094.40169594058, 14044.087134336236, 14201.360938757574, 14475.638109047359, 14492.0, 14677.779833302722, 13822.1862477642, 14617.488387906373, 15086.170260635332, 15093.698126037198, 15125.016873903161, 14626.85373146255, 14717.705645887236, 15101.47144849925, 15510.457134000257, 15686.764698529303, 9214.493202873906, 9971.650992215238, 10100.090991810772, 10106.595736170471, 10151.796964060824, 14530.636734081732, 14709.55871323862, 14914.105153690813, 14929.477468288558, 15228.695426091637], "unit": "ms", "name": "jquery.html", "value": 8815.890385203462}, {"lowerIsBetter": false, "replicates": [1544.6833399153174, 1670.507200375889, 1698.3291599818072, 1718.063655307857, 1725.8101608823026, 7427.702891884325, 7726.690932362712, 7823.92176078239, 7857.292843644071, 7887.684492620288, 7649.961750191255, 7803.804904877381, 7848.372130229583, 7885.763427097197, 7919.56442395668, 2245.9438514037156, 2291.3698732848434, 2297.1615360393503, 2310.584094862924, 2327.7555856635063, 2343.1564636730764, 2344.882755862209, 2386.6539351794067, 2387.606045002581, 2388.7491813359607, 2193.2652561391874, 2242.932712018642, 2261.4007288068674, 2266.7166604174477, 2292.6790249365104, 2242.013514053811, 2245.854019488736, 2249.4826189976234, 2251.448395143184, 2269.2057779776947, 2218.456478162844, 2358.610829213195, 2358.693369861907, 2362.83460157789, 2365.8580485170946, 7735.4585179037485, 7741.0, 7770.0, 7786.649600767979, 7809.687612495493, 4642.744649044301, 4670.883227919295, 4697.7181369117625, 4715.292706094114, 4730.266808644666, 2987.8506074696397, 3032.3328867649084, 3055.8472076396315, 3078.0304204175836, 3100.937981240385, 1019.5208252121491, 1019.7450637340664, 1036.1503567075001, 1052.5000624703205, 1092.2463500184847, 10533.631322903662, 10554.261201715883, 10624.787504249949, 10630.681079567548, 10638.0, 4118.258713431611, 4129.938050929239, 4151.066010147692, 4156.521999969982, 4213.9578604214175, 8616.09530999236, 8638.611262493077, 8645.394822362374, 8654.524001179996, 8662.523561204069, 4686.992296656235, 4688.5545873142, 4695.319178719066, 4707.058588282357, 4735.976320118378, 4269.658427325807, 4269.914601707948, 4273.786310684453, 4295.978520107442, 4313.094250207491, 3433.296174284266, 3443.1908501502126, 3448.1724386147143, 3463.7229021678204, 3480.460528618068, 1065.6802959112235, 1074.4520294649785, 1076.8169411200115, 1084.5932775209296, 1087.190043417659, 1070.7751372211924, 1085.1969542538623, 1125.0942990892247, 1254.4229654358915, 1323.8146659467682, 7257.818554536073, 7339.74310899116, 7347.118345798538, 7375.225601311892, 7388.778336649909, 1144.2733863996423, 1156.3871148291419, 1166.702490864824, 1174.747429302704, 1184.9170558060853, 5823.767049317979, 5830.329512106162, 5833.970830145822, 5870.471657550841, 5881.941180588225, 9024.142706442877, 9094.0, 9221.723348299562, 9231.861522077174, 9355.812883742286, 9122.0, 9150.908490915139, 9184.586693598803, 9215.400998935047, 9269.304802139866, 7209.35115839577, 7257.963710181521, 7262.382697470668, 7295.416366690652, 7341.632918354061, 7075.363217310467, 7088.610126443095, 7172.13934327884, 7195.892061618975, 7198.388137008413, 7962.641681124479, 8170.673173073011, 8202.220789025034, 8238.83522329562, 8340.541270230196, 8341.791455213426, 8352.248297653241, 8382.16178382157, 8386.616208325559, 8400.83198336042, 6702.865942681216, 6735.6295403753265, 6739.191297044386, 6759.797206083826, 6777.593344399352, 7293.343599076109, 7366.631668416665, 7367.152777430664, 7377.963110184414, 7383.2985866342615, 6760.425363844129, 6922.342377474132, 6960.199577048502, 6996.930030699627, 7059.5058345915295, 6454.903176452451, 6620.337966203341, 6632.038354438676, 6689.1972963244725, 6753.3921947025, 6938.93061069403, 6969.825754356181, 6985.1617805863625, 6987.301269873175, 6991.510594258353, 6142.600730952474, 6236.282827474717, 6278.497720182283, 6287.962486189727, 6292.465140463112, 6277.403646653561, 6283.403076707706, 6298.7480500781285, 6308.211473565804, 6323.905141422791], "unit": "ms", "name": "mootools.html", "value": 4460.545208595421}, {"lowerIsBetter": false, "replicates": [319.1049107254152, 325.42724804344357, 331.88384065577037, 338.47367343780445, 343.26198672853377, 6295.087212354211, 6392.625585499129, 6395.64823934684, 6425.1968503937005, 6550.0829883816305, 6320.146780184674, 6371.904421433671, 6400.551961362707, 6404.903926441096, 6416.454601358879, 4780.020095880336, 4790.496997815231, 4798.448178459487, 4815.638827087965, 4824.686395384307, 4811.951880481203, 4824.372831531896, 4830.9033819323595, 4833.275008748698, 4842.515748425147, 4663.323818046381, 4749.216379297412, 4755.667103302771, 4770.713757174563, 4776.116418462596, 4741.217699079648, 4744.8102075917095, 4753.358296629963, 4762.023785124076, 4763.571278584944, 4080.6327430531096, 4095.631393174599, 4096.549379568245, 4114.218298523301, 4115.991582062384, 6038.516918646497, 6042.96978515109, 6045.576809623284, 6051.969740151315, 6057.697115144269, 5439.129739241742, 5560.916586251169, 5561.054620714488, 5566.137248726453, 5570.554355651539, 6701.765438209688, 6729.932700673028, 6730.360615741497, 6732.629705366203, 6743.561668491532, 4550.362949187116, 4565.429321334833, 4567.109413664337, 4568.428946381702, 4573.542645735433, 6736.696848641823, 6879.484038697067, 6932.757353492654, 6946.235914049451, 7011.719531218745, 4599.0, 4651.604613607881, 4684.110019096361, 4688.882777930511, 4694.084653492536, 4696.412948381452, 4700.576948074689, 4712.740799256074, 4726.338312636233, 4731.408573928259, 5526.889462210814, 5538.723063846872, 5548.056830338852, 5548.334199896039, 5550.944490555043, 6615.86768264632, 6756.864862702719, 6760.797176084725, 6770.322967703289, 6807.863842723118, 1596.4811436283255, 1630.983690163107, 1635.6401591650056, 1642.1871173769061, 1645.2925242145993, 1066.626680661762, 1074.2802322443981, 1076.246627360835, 1081.983770243447, 1083.9403832789271, 6374.840628984312, 6431.485481161496, 6450.6774661266745, 6470.514711396664, 6475.676216189172, 6848.623325717033, 6880.931190688029, 6883.862322753517, 6903.689333979983, 6907.792766217021, 6384.265809431975, 6432.099506069154, 6432.35676432353, 6447.162558670756, 6468.611883287018, 3786.6213378662464, 3840.519935008124, 3848.749831260959, 3851.0757418219987, 3859.5368555772925, 7347.155077166088, 7365.705371785069, 7366.594837283894, 7373.631318434057, 7381.520201186906, 7030.3321184487395, 7226.132864056347, 7235.167955685163, 7270.454715896329, 7320.450966177558, 7367.963160184165, 7375.336219740142, 7377.88933166003, 7379.2251813558805, 7388.667509962064, 7064.434845212268, 7096.396806271525, 7102.573845569075, 7103.2186459488385, 7104.751333703295, 6898.16963906902, 7005.984132300686, 7007.789766307019, 7027.543209691354, 7027.964860175666, 7141.143062832493, 7241.927580724126, 7249.746258880914, 7259.346658800733, 7266.96366518164, 4636.536346365337, 4678.719276843401, 4707.882302942454, 4718.41019872516, 4721.913959789199, 6825.0, 6830.624315662686, 6985.3713165815325, 7011.368976792113, 7039.225685174524, 6807.489438292247, 6828.487863410165, 6830.7609233676585, 6855.760048398282, 6871.793846184622, 6764.2221144569, 6822.0, 6834.316568343126, 6840.110785597841, 6846.7945961621235, 6543.6400997943665, 6679.06493090958, 6691.5650482718465, 6694.263631000684, 6711.530192886451, 6719.193696756225, 6842.55523390978, 6850.349216824394, 6876.896846547406, 6885.793426197222, 6661.367170118831, 6671.232808227116, 6679.599224046573, 6683.064370987969, 6685.53201275906], "unit": "ms", "name": "prototype.html", "value": 5052.881879506292}, {"lowerIsBetter": false, "replicates": [853.6286715278852, 860.1957170046004, 862.4437237981502, 879.3536750488394, 888.6800751729378, 13562.118462299955, 13830.93084534576, 13848.515301964408, 13866.514671986482, 13866.584002479916, 11730.237534560247, 11798.0, 11798.58704945325, 11813.468393922272, 11891.108166887496, 804.047204063184, 813.6663967773214, 815.2418251026543, 816.5753808019826, 825.1707034430389, 823.8434697407473, 824.6412810427483, 827.6854795177854, 829.274384913201, 830.149097175394, 854.0520022774715, 861.4701958295666, 862.6420035685184, 864.0495454999482, 866.030046348091, 843.4939036578065, 845.5476320168711, 846.3652260804397, 848.5799529233008, 850.6214734443175, 622.8287221014257, 627.2347735762363, 631.7315141065029, 631.8894193516162, 632.1402892066832, 13845.0, 13869.930650346785, 13872.445102196001, 13910.095843770123, 13930.860691393058, 11652.126090543243, 11952.282863028246, 11973.880261197364, 12087.637370878887, 12267.631971040882, 10071.446070466121, 10307.278490505667, 10325.535350909227, 10384.480775961247, 10405.947970260177, 3568.589612194605, 3633.0372451300163, 3633.418653015531, 3639.636036396366, 3668.6881615062753, 47224.05551888977, 47254.32948046222, 47332.29001564979, 48164.27753583699, 48741.51258487405, 9354.158125768714, 9484.95257523708, 9679.225661947028, 9736.367136136128, 9795.461249631197, 18043.909780451275, 18075.36736214226, 18133.546661333574, 18177.272909083753, 18230.544236394195, 7580.507267027626, 7596.050493688289, 7677.539347639159, 7823.960880195563, 7882.960585197037, 4284.583957442787, 4299.849505267363, 4301.07526881722, 4317.935230971538, 4369.169857727022, 2982.328975980387, 2987.3129180288656, 2992.311768293305, 2999.355138645158, 3007.248187953012, 557.1030640668521, 560.4451592922977, 560.6720068759821, 577.295699246918, 581.8167277307634, 620.6648409858716, 654.1496055128314, 685.2256949646867, 686.972273640649, 726.5096060159372, 8470.280026197843, 9211.30915181364, 9304.581293841793, 9453.952730236304, 9528.19010384125, 534.9968808484092, 614.9944840186328, 694.4617921111078, 733.453577085075, 738.8732183420219, 3909.9413508797393, 4103.9179216416105, 4231.386448964883, 4292.334688123284, 4488.753118578445, 51161.23258151131, 60078.0, 60702.08946865801, 60896.0, 61194.69402653047, 46867.0, 47106.0, 47576.524234757904, 47710.522894770605, 48096.0, 44882.0, 45458.77270613692, 45478.545214547434, 45893.7705311478, 46153.07693846104, 8740.431871928304, 8951.73144805657, 9108.271338292789, 9164.266858651425, 9288.256939444964, 33864.0, 34065.31869362648, 34258.48612270768, 34838.30323393569, 35252.47121293234, 39760.2047959045, 40773.59226407698, 44520.55479445294, 44832.10335793331, 45068.77465612651, 10849.294795838245, 10948.124150068135, 11105.278156919774, 11122.610708625483, 11256.155788315746, 12135.271883687006, 12727.045471589483, 12754.170978886343, 12798.296093714935, 12938.611841644766, 11634.592789252336, 11940.70148246266, 12061.216020958609, 12329.938350308192, 12406.689832754253, 11430.542778288775, 11701.707457313294, 11854.110941679237, 11954.222975506913, 12068.577599784317, 11552.537898484303, 11638.12714046433, 11652.592159274385, 12037.277763334228, 12121.212121212093, 9779.168770654574, 9826.312158149147, 9841.163501102486, 10012.254841158267, 10368.53341599645, 9080.954595226982, 9462.337636365664, 9697.563609637724, 9827.467356664025, 10076.798464030824], "unit": "ms", "name": "yui.html", "value": 6364.643128882583}], "name": "dromaeo_css", "value": 7074.362308098958}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 107.0, 110.0, 109.0, 109.0, 122.0, 109.0, 110.0, 112.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 110.9}, {"lowerIsBetter": true, "replicates": [148.0, 125.0, 157.0, 171.0, 125.0, 148.0, 154.0, 130.0, 164.0, 136.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.8}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 163.0, 164.0, 164.0, 166.0, 162.0, 164.0, 169.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 164.1}, {"lowerIsBetter": true, "replicates": [72.0, 75.0, 73.0, 73.0, 72.0, 136.0, 71.0, 73.0, 72.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 78.9}, {"lowerIsBetter": true, "replicates": [91.0, 90.0, 141.0, 90.0, 89.0, 89.0, 129.0, 91.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 103.5}, {"lowerIsBetter": true, "replicates": [111.0, 111.0, 111.0, 109.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.3}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 147.0, 146.0, 148.0, 149.0, 146.0, 147.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.2}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 96.0, 97.0, 96.0, 98.0, 96.0, 97.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.7}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 65.0, 62.0, 61.0, 64.0, 62.0, 62.0, 61.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 62.1}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 68.0, 69.0, 69.0, 70.0, 70.0, 68.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [84.0, 83.0, 86.0, 85.0, 85.0, 85.0, 93.0, 85.0, 86.0, 94.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.6}, {"lowerIsBetter": true, "replicates": [120.0, 116.0, 114.0, 126.0, 122.0, 121.0, 129.0, 134.0, 116.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.6}, {"lowerIsBetter": true, "replicates": [142.0, 155.0, 148.0, 160.0, 153.0, 158.0, 150.0, 153.0, 163.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 152.4}, {"lowerIsBetter": true, "replicates": [71.0, 67.0, 68.0, 72.0, 68.0, 66.0, 66.0, 67.0, 68.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.8}], "name": "kraken", "value": 1516.6}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.47244880972198], "unit": "score", "name": "Richards", "value": 212.05911400000002}, {"lowerIsBetter": false, "replicates": [192.8268414963363], "unit": "score", "name": "DeltaBlue", "value": 342.8879479999999}, {"lowerIsBetter": false, "replicates": [284.09090909090907], "unit": "score", "name": "Encrypt", "value": 936.95712}, {"lowerIsBetter": false, "replicates": [5050.50505050505], "unit": "score", "name": "Decrypt", "value": 52.70383799999996}, {"lowerIsBetter": false, "replicates": [1062.6992561105208], "unit": "score", "name": "RayTrace", "value": 696.3296489999998}, {"lowerIsBetter": false, "replicates": [708.7172218284904], "unit": "score", "name": "Earley", "value": 940.379293}, {"lowerIsBetter": false, "replicates": [12156.626506024097], "unit": "score", "name": "Boyer", "value": 54.82302180376615}, {"lowerIsBetter": false, "replicates": [24902.439024390245], "unit": "score", "name": "RegExp", "value": 36.58215964740448}, {"lowerIsBetter": false, "replicates": [622.66500622665], "unit": "score", "name": "Splay", "value": 130.874546}, {"lowerIsBetter": false, "replicates": [4882.926829268293], "unit": "score", "name": "NavierStokes", "value": 303.91608391608383}], "name": "v8_7", "value": 21331.09956215654}]} 07:45:18 INFO - RETURN: dromaeo_css: 7074.36 07:45:18 INFO - RETURN: kraken: 1516.60 07:45:18 INFO - RETURN: v8_7: 21331.10 07:45:18 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21331.10"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1516.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7074.36"}}} 07:45:18 INFO - Return code: 0 07:45:18 INFO - # TBPL SUCCESS # 07:45:18 INFO - Running post-action listener: _resource_record_post_action 07:45:18 INFO - Running post-run listener: _resource_record_post_run 07:45:20 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 2535424; Write bytes: 525004800; Read time: 1392; Write time: 496192 07:45:20 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 183685120; Read time: 0; Write time: 266804 07:45:20 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 2535424; Write bytes: 284119040; Read time: 1392; Write time: 146208 07:45:20 INFO - Running post-run listener: _upload_blobber_files 07:45:20 INFO - Blob upload gear active. 07:45:20 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:45:20 INFO - Copying logs to upload dir... 07:45:20 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.667079 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2015-11-20 07:45:19.578743) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 07:45:19.582195) ========= 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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009983 build_url: 'https://queue.taskcluster.net/v1/task/7G0AHU9ZTt6UuJdParsNvg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-20 07:45:19.637136) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:45:19.637485) ========= 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=70a720fcaf18edea6683c2dc000001ad-1448033056.680891-1882933075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-20 07:45:19.690478) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 07:45:19.691085) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-20 07:45:19.691604) ========= ========= Total master_lag: 5.56 =========