builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1447900055.78 results: success (0) buildid: 20151118154243 builduid: c9aa6ac322ad4a7981cc3e157b3d7972 revision: a1ac4564029c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.778606) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.779077) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.779377) ========= 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=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006441 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.803360) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.803662) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.828481) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.828753) ========= 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=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004687 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.882084) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.882350) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.882674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:35.882956) ========= 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=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-18 18:27:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 260K=0.05s 2015-11-18 18:27:36 (260 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.196987 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.092250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.092582) ========= 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=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018144 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.142851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.143230) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a1ac4564029c --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 a1ac4564029c --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=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-18 18:27:36,293 Setting DEBUG logging. 2015-11-18 18:27:36,293 attempt 1/10 2015-11-18 18:27:36,293 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a1ac4564029c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-18 18:27:36,585 unpacking tar archive at: fx-team-a1ac4564029c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.572074 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.730950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.731334) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.747737) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.747996) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:27:36.748424) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-18 18:27:36.748701) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:27:36 INFO - MultiFileLogger online at 20151118 18:27:36 in /builds/slave/test-pgo 18:27:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:27:36 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:27:36 INFO - {'append_to_log': False, 18:27:36 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:27:36 INFO - 'blob_upload_branch': 'Fx-Team', 18:27:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:27:36 INFO - 'branch': 'Fx-Team', 18:27:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:27:36 INFO - 'config_files': ('talos/linux_config.py',), 18:27:36 INFO - 'default_actions': ('clobber', 18:27:36 INFO - 'read-buildbot-config', 18:27:36 INFO - 'download-and-extract', 18:27:36 INFO - 'populate-webroot', 18:27:36 INFO - 'create-virtualenv', 18:27:36 INFO - 'install', 18:27:36 INFO - 'run-tests'), 18:27:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:27:36 INFO - 'download_minidump_stackwalk': True, 18:27:36 INFO - 'download_symbols': 'ondemand', 18:27:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:27:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:27:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:27:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:27:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:27:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:27:36 INFO - 'installer_path': 'installer.exe', 18:27:36 INFO - 'log_level': 'info', 18:27:36 INFO - 'log_name': 'talos', 18:27:36 INFO - 'log_to_console': True, 18:27:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:27:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:27:36 INFO - 'opt_config_files': (), 18:27:36 INFO - 'pip_index': False, 18:27:36 INFO - 'sps_profile': False, 18:27:36 INFO - 'sps_profile_interval': 0, 18:27:36 INFO - 'suite': 'dromaeojs', 18:27:36 INFO - 'system_bits': '64', 18:27:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:27:36 INFO - 'title': 'talos-linux64-ix-014', 18:27:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:27:36 INFO - 'use_talos_json': True, 18:27:36 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:27:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:27:36 INFO - 'work_dir': 'build'} 18:27:36 INFO - ##### 18:27:36 INFO - ##### Running clobber step. 18:27:36 INFO - ##### 18:27:36 INFO - Running pre-action listener: _resource_record_pre_action 18:27:36 INFO - Running main action method: clobber 18:27:36 INFO - rmtree: /builds/slave/test-pgo/build 18:27:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:27:37 INFO - Running post-action listener: _resource_record_post_action 18:27:37 INFO - ##### 18:27:37 INFO - ##### Running read-buildbot-config step. 18:27:37 INFO - ##### 18:27:37 INFO - Running pre-action listener: _resource_record_pre_action 18:27:37 INFO - Running main action method: read_buildbot_config 18:27:37 INFO - Using buildbot properties: 18:27:37 INFO - { 18:27:37 INFO - "properties": { 18:27:37 INFO - "buildnumber": 791, 18:27:37 INFO - "product": "firefox", 18:27:37 INFO - "script_repo_revision": "production", 18:27:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:27:37 INFO - "repository": "", 18:27:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 18:27:37 INFO - "buildid": "20151118154243", 18:27:37 INFO - "slavename": "talos-linux64-ix-014", 18:27:37 INFO - "pgo_build": "True", 18:27:37 INFO - "basedir": "/builds/slave/test-pgo", 18:27:37 INFO - "project": "", 18:27:37 INFO - "platform": "ubuntu64_hw", 18:27:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:27:37 INFO - "slavebuilddir": "test", 18:27:37 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:27:37 INFO - "branch": "fx-team", 18:27:37 INFO - "repo_path": "integration/fx-team", 18:27:37 INFO - "stage_platform": "linux64-pgo", 18:27:37 INFO - "builduid": "c9aa6ac322ad4a7981cc3e157b3d7972", 18:27:37 INFO - "revision": "a1ac4564029c" 18:27:37 INFO - }, 18:27:37 INFO - "sourcestamp": { 18:27:37 INFO - "repository": "", 18:27:37 INFO - "hasPatch": false, 18:27:37 INFO - "project": "", 18:27:37 INFO - "branch": "fx-team-linux64-pgo-talos", 18:27:37 INFO - "changes": [ 18:27:37 INFO - { 18:27:37 INFO - "category": null, 18:27:37 INFO - "files": [ 18:27:37 INFO - { 18:27:37 INFO - "url": null, 18:27:37 INFO - "name": "https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:27:37 INFO - } 18:27:37 INFO - ], 18:27:37 INFO - "repository": "", 18:27:37 INFO - "rev": "a1ac4564029c", 18:27:37 INFO - "who": "mozci-bot@mozilla.com", 18:27:37 INFO - "when": 1447900001, 18:27:37 INFO - "number": 6680569, 18:27:37 INFO - "comments": "", 18:27:37 INFO - "project": "", 18:27:37 INFO - "at": "Wed 18 Nov 2015 18:26:41", 18:27:37 INFO - "branch": "fx-team-linux64-pgo-talos", 18:27:37 INFO - "revlink": "", 18:27:37 INFO - "properties": [ 18:27:37 INFO - [ 18:27:37 INFO - "buildid", 18:27:37 INFO - "20151118154243", 18:27:37 INFO - "Change" 18:27:37 INFO - ], 18:27:37 INFO - [ 18:27:37 INFO - "builduid", 18:27:37 INFO - "c9aa6ac322ad4a7981cc3e157b3d7972", 18:27:37 INFO - "Change" 18:27:37 INFO - ], 18:27:37 INFO - [ 18:27:37 INFO - "pgo_build", 18:27:37 INFO - "True", 18:27:37 INFO - "Change" 18:27:37 INFO - ] 18:27:37 INFO - ], 18:27:37 INFO - "revision": "a1ac4564029c" 18:27:37 INFO - } 18:27:37 INFO - ], 18:27:37 INFO - "revision": "a1ac4564029c" 18:27:37 INFO - } 18:27:37 INFO - } 18:27:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:27:37 INFO - Running post-action listener: _resource_record_post_action 18:27:37 INFO - ##### 18:27:37 INFO - ##### Running download-and-extract step. 18:27:37 INFO - ##### 18:27:37 INFO - Running pre-action listener: _resource_record_pre_action 18:27:37 INFO - Running main action method: download_and_extract 18:27:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:27: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')]} 18:27:37 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:27:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json 18:27:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json 18:27:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:27:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:27:41 INFO - Downloaded 1302 bytes. 18:27:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:27:41 INFO - Using the following test package requirements: 18:27:41 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:41 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:27:41 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:41 INFO - u'jsshell-linux-x86_64.zip'], 18:27:41 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:27:41 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:41 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:27:41 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:27:41 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:27:41 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:27:41 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:27:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:27:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:27: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')]} 18:27:41 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:27:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:43 INFO - Downloaded 22477005 bytes. 18:27:43 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 18:27:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:43 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 18:27:44 INFO - Return code: 0 18:27:44 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 18:27: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')]} 18:27:44 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:27:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:45 INFO - Downloaded 22477005 bytes. 18:27:45 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 18:27:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:27:45 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 18:27:47 INFO - Return code: 0 18:27:47 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')]} 18:27:47 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:27:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:48 INFO - Downloaded 11130997 bytes. 18:27:48 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 18:27:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:27:48 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 18:27:48 INFO - Return code: 0 18:27: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')]} 18:27:48 INFO - https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:27:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5xH5cKVQXCNYRO7PYExIg/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 18:27:52 INFO - Downloaded 57206488 bytes. 18:27:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:52 INFO - mkdir: /builds/slave/test-pgo/properties 18:27:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:27:52 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:27:52 INFO - Contents: 18:27:52 INFO - build_url:https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:27:52 INFO - Running post-action listener: _resource_record_post_action 18:27:52 INFO - Running post-action listener: set_extra_try_arguments 18:27:52 INFO - ##### 18:27:52 INFO - ##### Running populate-webroot step. 18:27:52 INFO - ##### 18:27:52 INFO - Running pre-action listener: _resource_record_pre_action 18:27:52 INFO - Running main action method: populate_webroot 18:27:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:27:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:27:52 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:27:52 INFO - '../fennec_ids.txt'], 18:27:52 INFO - 'tests': ['tcheck2']}, 18:27:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:27:52 INFO - '--tppagecycles', 18:27:52 INFO - '7'], 18:27:52 INFO - 'tests': ['tsvgm']}}, 18:27:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:27:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:27:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:27:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:27:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'tests': ['damp', 'tps']}, 18:27:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['damp', 'tps']}, 18:27:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:27:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:27:52 INFO - 'other': {'tests': ['a11yr', 18:27:52 INFO - 'ts_paint', 18:27:52 INFO - 'tpaint', 18:27:52 INFO - 'sessionrestore', 18:27:52 INFO - 'sessionrestore_no_auto_restore']}, 18:27:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['a11yr', 18:27:52 INFO - 'ts_paint', 18:27:52 INFO - 'tpaint', 18:27:52 INFO - 'sessionrestore', 18:27:52 INFO - 'sessionrestore_no_auto_restore']}, 18:27:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['a11yr', 18:27:52 INFO - 'ts_paint', 18:27:52 INFO - 'tpaint', 18:27:52 INFO - 'sessionrestore', 18:27:52 INFO - 'sessionrestore_no_auto_restore']}, 18:27:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['a11yr', 18:27:52 INFO - 'ts_paint', 18:27:52 INFO - 'tpaint', 18:27:52 INFO - 'sessionrestore', 18:27:52 INFO - 'sessionrestore_no_auto_restore']}, 18:27:52 INFO - 'other_l64': {'tests': ['a11yr', 18:27:52 INFO - 'ts_paint', 18:27:52 INFO - 'tpaint', 18:27:52 INFO - 'sessionrestore', 18:27:52 INFO - 'sessionrestore_no_auto_restore']}, 18:27:52 INFO - 'other_nol64': {'tests': ['a11yr', 18:27:52 INFO - 'ts_paint', 18:27:52 INFO - 'tpaint', 18:27:52 INFO - 'sessionrestore', 18:27:52 INFO - 'sessionrestore_no_auto_restore']}, 18:27:52 INFO - 'svgr': {'tests': ['tsvgx', 18:27:52 INFO - 'tsvgr_opacity', 18:27:52 INFO - 'tart', 18:27:52 INFO - 'tscrollx', 18:27:52 INFO - 'cart']}, 18:27:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['tsvgx', 18:27:52 INFO - 'tsvgr_opacity', 18:27:52 INFO - 'tart', 18:27:52 INFO - 'tscrollx', 18:27:52 INFO - 'cart']}, 18:27:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'tests': ['tp5o']}, 18:27:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'talos_options': ['--e10s'], 18:27:52 INFO - 'tests': ['tp5o']}, 18:27:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'talos_options': ['--xperf_path', 18:27:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:52 INFO - 'tests': ['tp5n']}, 18:27:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:52 INFO - 'talos_options': ['--e10s', 18:27:52 INFO - '--xperf_path', 18:27:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:52 INFO - 'tests': ['tp5n']}}, 18:27:52 INFO - 'talos.zip': {'path': '', 18:27:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:27:52 INFO - Running post-action listener: _resource_record_post_action 18:27:52 INFO - ##### 18:27:52 INFO - ##### Running create-virtualenv step. 18:27:52 INFO - ##### 18:27:52 INFO - Running pre-action listener: _resource_record_pre_action 18:27:52 INFO - Running main action method: create_virtualenv 18:27:52 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:27:52 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 18:27:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:27:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:27:52 INFO - Using real prefix '/usr' 18:27:52 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:27:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:27:55 INFO - Installing pip.................done. 18:27:55 INFO - Return code: 0 18:27:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:27:55 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')]} 18:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:55 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')]} 18:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:55 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 0x7fb598c98c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x292d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, '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': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', '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 18:27:55 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 18:27:55 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 18:27:55 INFO - Using env: {'DISPLAY': ':0', 18:27:55 INFO - 'HOME': '/home/cltbld', 18:27:55 INFO - 'LANG': 'en_US.UTF-8', 18:27:55 INFO - 'LANGUAGE': 'en_US:en', 18:27:55 INFO - 'LOGNAME': 'cltbld', 18:27:55 INFO - 'MAIL': '/var/mail/cltbld', 18:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:55 INFO - 'MOZ_NO_REMOTE': '1', 18:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:55 INFO - 'NO_EM_RESTART': '1', 18:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:55 INFO - 'SHELL': '/bin/bash', 18:27:55 INFO - 'SHLVL': '1', 18:27:55 INFO - 'TERM': 'linux', 18:27:55 INFO - 'TMOUT': '86400', 18:27:55 INFO - 'USER': 'cltbld', 18:27:55 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:55 INFO - '_': '/tools/buildbot/bin/python'} 18:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:55 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 18:27:55 INFO - Cleaning up... 18:27:55 INFO - Return code: 0 18:27:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:27:55 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')]} 18:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:55 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')]} 18:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:55 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 0x7fb598c98c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x292d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, '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': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', '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 18:27:55 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 18:27:55 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 18:27:55 INFO - Using env: {'DISPLAY': ':0', 18:27:55 INFO - 'HOME': '/home/cltbld', 18:27:55 INFO - 'LANG': 'en_US.UTF-8', 18:27:55 INFO - 'LANGUAGE': 'en_US:en', 18:27:55 INFO - 'LOGNAME': 'cltbld', 18:27:55 INFO - 'MAIL': '/var/mail/cltbld', 18:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:55 INFO - 'MOZ_NO_REMOTE': '1', 18:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:55 INFO - 'NO_EM_RESTART': '1', 18:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:55 INFO - 'SHELL': '/bin/bash', 18:27:55 INFO - 'SHLVL': '1', 18:27:55 INFO - 'TERM': 'linux', 18:27:55 INFO - 'TMOUT': '86400', 18:27:55 INFO - 'USER': 'cltbld', 18:27:55 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:55 INFO - '_': '/tools/buildbot/bin/python'} 18:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:55 INFO - Downloading/unpacking psutil>=0.7.1 18:27:55 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 18:27:55 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 18:27:55 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 18:27:55 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 18:27:55 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 18:27:55 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 18:27:57 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:27:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:27:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:27:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:57 INFO - Installing collected packages: psutil 18:27:57 INFO - Running setup.py install for psutil 18:27:57 INFO - building 'psutil._psutil_linux' extension 18:27:57 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 18:27:57 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 18:27:57 INFO - building 'psutil._psutil_posix' extension 18:27:57 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 18:27:58 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 18:27:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:58 INFO - Successfully installed psutil 18:27:58 INFO - Cleaning up... 18:27:58 INFO - Return code: 0 18:27:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb598c98c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x292d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, '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': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', '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 18:27:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:27:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:27:58 INFO - Using env: {'DISPLAY': ':0', 18:27:58 INFO - 'HOME': '/home/cltbld', 18:27:58 INFO - 'LANG': 'en_US.UTF-8', 18:27:58 INFO - 'LANGUAGE': 'en_US:en', 18:27:58 INFO - 'LOGNAME': 'cltbld', 18:27:58 INFO - 'MAIL': '/var/mail/cltbld', 18:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:58 INFO - 'MOZ_NO_REMOTE': '1', 18:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:58 INFO - 'NO_EM_RESTART': '1', 18:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:27:58 INFO - 'PWD': '/builds/slave/test-pgo', 18:27:58 INFO - 'SHELL': '/bin/bash', 18:27:58 INFO - 'SHLVL': '1', 18:27:58 INFO - 'TERM': 'linux', 18:27:58 INFO - 'TMOUT': '86400', 18:27:58 INFO - 'USER': 'cltbld', 18:27:58 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:58 INFO - '_': '/tools/buildbot/bin/python'} 18:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:28:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:28:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:28:00 INFO - Installing collected packages: mozsystemmonitor 18:28:00 INFO - Running setup.py install for mozsystemmonitor 18:28:00 INFO - Successfully installed mozsystemmonitor 18:28:00 INFO - Cleaning up... 18:28:00 INFO - Return code: 0 18:28:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:28:00 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')]} 18:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:00 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')]} 18:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:00 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 0x7fb598c98c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x292d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, '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': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', '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 18:28:00 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 18:28:00 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 18:28:00 INFO - Using env: {'DISPLAY': ':0', 18:28:00 INFO - 'HOME': '/home/cltbld', 18:28:00 INFO - 'LANG': 'en_US.UTF-8', 18:28:00 INFO - 'LANGUAGE': 'en_US:en', 18:28:00 INFO - 'LOGNAME': 'cltbld', 18:28:00 INFO - 'MAIL': '/var/mail/cltbld', 18:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:00 INFO - 'MOZ_NO_REMOTE': '1', 18:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:00 INFO - 'NO_EM_RESTART': '1', 18:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:00 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:00 INFO - 'SHELL': '/bin/bash', 18:28:00 INFO - 'SHLVL': '1', 18:28:00 INFO - 'TERM': 'linux', 18:28:00 INFO - 'TMOUT': '86400', 18:28:00 INFO - 'USER': 'cltbld', 18:28:00 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:00 INFO - '_': '/tools/buildbot/bin/python'} 18:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:00 INFO - Downloading/unpacking blobuploader==1.2.4 18:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:02 INFO - Downloading blobuploader-1.2.4.tar.gz 18:28:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:28:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:28:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:28:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:28:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 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 18:28:02 INFO - Downloading docopt-0.6.1.tar.gz 18:28:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:28:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:28:03 INFO - Installing collected packages: blobuploader, requests, docopt 18:28:03 INFO - Running setup.py install for blobuploader 18:28:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:28:03 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:28:03 INFO - Running setup.py install for requests 18:28:03 INFO - Running setup.py install for docopt 18:28:03 INFO - Successfully installed blobuploader requests docopt 18:28:03 INFO - Cleaning up... 18:28:03 INFO - Return code: 0 18:28:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:03 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 0x7fb598c98c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x292d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, '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': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', '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 18:28:03 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 18:28:03 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 18:28:03 INFO - Using env: {'DISPLAY': ':0', 18:28:03 INFO - 'HOME': '/home/cltbld', 18:28:03 INFO - 'LANG': 'en_US.UTF-8', 18:28:03 INFO - 'LANGUAGE': 'en_US:en', 18:28:03 INFO - 'LOGNAME': 'cltbld', 18:28:03 INFO - 'MAIL': '/var/mail/cltbld', 18:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:03 INFO - 'MOZ_NO_REMOTE': '1', 18:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:03 INFO - 'NO_EM_RESTART': '1', 18:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:03 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:03 INFO - 'SHELL': '/bin/bash', 18:28:03 INFO - 'SHLVL': '1', 18:28:03 INFO - 'TERM': 'linux', 18:28:03 INFO - 'TMOUT': '86400', 18:28:03 INFO - 'USER': 'cltbld', 18:28:03 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:03 INFO - '_': '/tools/buildbot/bin/python'} 18:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:28:03 INFO - Running setup.py (path:/tmp/pip-R1whTX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:28:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:28:03 INFO - Running setup.py (path:/tmp/pip-HGs_Ky-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:28:04 INFO - Running setup.py (path:/tmp/pip-Nxd2Ku-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:28:04 INFO - Running setup.py (path:/tmp/pip-h2QTPC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:28:04 INFO - Running setup.py (path:/tmp/pip-Gj2Xji-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:28:04 INFO - Running setup.py (path:/tmp/pip-t8yxFa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:28:04 INFO - Running setup.py (path:/tmp/pip-mdILI8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:28:04 INFO - Running setup.py (path:/tmp/pip-u17eAd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:28:04 INFO - Running setup.py (path:/tmp/pip-mlgpog-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:28:04 INFO - Running setup.py (path:/tmp/pip-6geht2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:28:04 INFO - Running setup.py (path:/tmp/pip-KKxfz7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:28:04 INFO - Running setup.py (path:/tmp/pip-8rI_AC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:28:04 INFO - Running setup.py (path:/tmp/pip-OxB7qr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:28:04 INFO - Running setup.py (path:/tmp/pip-J_dSUv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:28:04 INFO - Running setup.py (path:/tmp/pip-uALEXC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:28:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:28:04 INFO - Running setup.py (path:/tmp/pip-lKHISp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:28:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:28:05 INFO - Running setup.py (path:/tmp/pip-xKWdz0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:28:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:28:05 INFO - Running setup.py install for manifestparser 18:28:05 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Running setup.py install for mozcrash 18:28:05 INFO - Running setup.py install for mozdebug 18:28:05 INFO - Running setup.py install for mozdevice 18:28:05 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Running setup.py install for mozfile 18:28:05 INFO - Running setup.py install for mozhttpd 18:28:05 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Running setup.py install for mozinfo 18:28:05 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Running setup.py install for mozInstall 18:28:05 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:28:05 INFO - Running setup.py install for mozleak 18:28:05 INFO - Running setup.py install for mozlog 18:28:06 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:28:06 INFO - Running setup.py install for moznetwork 18:28:06 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:28:06 INFO - Running setup.py install for mozprocess 18:28:06 INFO - Running setup.py install for mozprofile 18:28:06 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:28:06 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:28:06 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:28:06 INFO - Running setup.py install for mozrunner 18:28:06 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:28:06 INFO - Running setup.py install for mozscreenshot 18:28:06 INFO - Running setup.py install for moztest 18:28:06 INFO - Running setup.py install for mozversion 18:28:06 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:28:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:28:06 INFO - Cleaning up... 18:28:06 INFO - Return code: 0 18:28:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:28: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')]} 18:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28: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')]} 18:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fb598c98c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x292d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, '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': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', '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 18:28:06 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 18:28:06 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 18:28:06 INFO - Using env: {'DISPLAY': ':0', 18:28:06 INFO - 'HOME': '/home/cltbld', 18:28:06 INFO - 'LANG': 'en_US.UTF-8', 18:28:06 INFO - 'LANGUAGE': 'en_US:en', 18:28:06 INFO - 'LOGNAME': 'cltbld', 18:28:06 INFO - 'MAIL': '/var/mail/cltbld', 18:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:06 INFO - 'MOZ_NO_REMOTE': '1', 18:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:06 INFO - 'NO_EM_RESTART': '1', 18:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:06 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:06 INFO - 'SHELL': '/bin/bash', 18:28:06 INFO - 'SHLVL': '1', 18:28:06 INFO - 'TERM': 'linux', 18:28:06 INFO - 'TMOUT': '86400', 18:28:06 INFO - 'USER': 'cltbld', 18:28:06 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:06 INFO - '_': '/tools/buildbot/bin/python'} 18:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:28:07 INFO - Running setup.py (path:/tmp/pip-okUqVy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:28:07 INFO - Running setup.py (path:/tmp/pip-QnTkEg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:28:07 INFO - Running setup.py (path:/tmp/pip-cmjpRD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:28:07 INFO - Running setup.py (path:/tmp/pip-Q_vukS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:28:07 INFO - Running setup.py (path:/tmp/pip-m__dlw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:28:07 INFO - Running setup.py (path:/tmp/pip-qH2Rve-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:28:07 INFO - Running setup.py (path:/tmp/pip-Jr0dDE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:28:07 INFO - Running setup.py (path:/tmp/pip-ht4nak-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:28:07 INFO - Running setup.py (path:/tmp/pip-wV0Onv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:28:07 INFO - Running setup.py (path:/tmp/pip-6VKeG2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:28:07 INFO - Running setup.py (path:/tmp/pip-5NMyBN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:28:07 INFO - Running setup.py (path:/tmp/pip-bskXPL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:28:07 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)) 18:28:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:28:07 INFO - Running setup.py (path:/tmp/pip-1mTsYS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:28:08 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)) 18:28:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:28:08 INFO - Running setup.py (path:/tmp/pip-CkSeH_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:28:08 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)) 18:28:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:28:08 INFO - Running setup.py (path:/tmp/pip-jPqPwp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:28:08 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)) 18:28:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:28:08 INFO - Running setup.py (path:/tmp/pip-RHVIai-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:28:08 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)) 18:28:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:28:08 INFO - Running setup.py (path:/tmp/pip-7yJ0Wu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:28:08 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)) 18:28:08 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)) 18:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:28:08 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)) 18:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:28:08 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)) 18:28:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:28:09 INFO - Downloading blessings-1.5.1.tar.gz 18:28:09 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 18:28:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:28:10 INFO - Installing collected packages: blessings 18:28:10 INFO - Running setup.py install for blessings 18:28:10 INFO - Successfully installed blessings 18:28:10 INFO - Cleaning up... 18:28:10 INFO - Return code: 0 18:28:10 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:28:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:28:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:28:10 INFO - Reading from file tmpfile_stdout 18:28:10 INFO - Current package versions: 18:28:10 INFO - argparse == 1.2.1 18:28:10 INFO - blessings == 1.5.1 18:28:10 INFO - blobuploader == 1.2.4 18:28:10 INFO - docopt == 0.6.1 18:28:10 INFO - manifestparser == 1.1 18:28:10 INFO - mozInstall == 1.12 18:28:10 INFO - mozcrash == 0.16 18:28:10 INFO - mozdebug == 0.1 18:28:10 INFO - mozdevice == 0.47 18:28:10 INFO - mozfile == 1.2 18:28:10 INFO - mozhttpd == 0.7 18:28:10 INFO - mozinfo == 0.9 18:28:10 INFO - mozleak == 0.1 18:28:10 INFO - mozlog == 3.0 18:28:10 INFO - moznetwork == 0.27 18:28:10 INFO - mozprocess == 0.22 18:28:10 INFO - mozprofile == 0.27 18:28:10 INFO - mozrunner == 6.11 18:28:10 INFO - mozscreenshot == 0.1 18:28:10 INFO - mozsystemmonitor == 0.0 18:28:10 INFO - moztest == 0.7 18:28:10 INFO - mozversion == 1.4 18:28:10 INFO - psutil == 3.1.1 18:28:10 INFO - requests == 1.2.3 18:28:10 INFO - wsgiref == 0.1.2 18:28:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:28:10 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')]} 18:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:10 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')]} 18:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:10 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 0x7fb598c98c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287b6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x292d7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, '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': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', '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 18:28:10 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 18:28:10 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 18:28:10 INFO - Using env: {'DISPLAY': ':0', 18:28:10 INFO - 'HOME': '/home/cltbld', 18:28:10 INFO - 'LANG': 'en_US.UTF-8', 18:28:10 INFO - 'LANGUAGE': 'en_US:en', 18:28:10 INFO - 'LOGNAME': 'cltbld', 18:28:10 INFO - 'MAIL': '/var/mail/cltbld', 18:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:10 INFO - 'MOZ_NO_REMOTE': '1', 18:28:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:10 INFO - 'NO_EM_RESTART': '1', 18:28:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:10 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:10 INFO - 'SHELL': '/bin/bash', 18:28:10 INFO - 'SHLVL': '1', 18:28:10 INFO - 'TERM': 'linux', 18:28:10 INFO - 'TMOUT': '86400', 18:28:10 INFO - 'USER': 'cltbld', 18:28:10 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:10 INFO - '_': '/tools/buildbot/bin/python'} 18:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 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)) 18:28:10 INFO - Cleaning up... 18:28:10 INFO - Return code: 0 18:28:10 INFO - Running post-action listener: _resource_record_post_action 18:28:10 INFO - Running post-action listener: _start_resource_monitoring 18:28:10 INFO - Starting resource monitoring. 18:28:10 INFO - ##### 18:28:10 INFO - ##### Running install step. 18:28:10 INFO - ##### 18:28:10 INFO - Running pre-action listener: _resource_record_pre_action 18:28:10 INFO - Running main action method: install 18:28:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:28:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:28:10 INFO - Reading from file tmpfile_stdout 18:28:10 INFO - Detecting whether we're running mozinstall >=1.0... 18:28:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:28:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:28:10 INFO - Reading from file tmpfile_stdout 18:28:10 INFO - Output received: 18:28:10 INFO - Usage: mozinstall [options] installer 18:28:10 INFO - Options: 18:28:10 INFO - -h, --help show this help message and exit 18:28:10 INFO - -d DEST, --destination=DEST 18:28:10 INFO - Directory to install application into. [default: 18:28:10 INFO - "/builds/slave/test-pgo"] 18:28:10 INFO - --app=APP Application being installed. [default: firefox] 18:28:10 INFO - mkdir: /builds/slave/test-pgo/build/application 18:28:10 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'] 18:28:10 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 18:28:25 INFO - Reading from file tmpfile_stdout 18:28:25 INFO - Output received: 18:28:25 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:28:25 INFO - Running post-action listener: _resource_record_post_action 18:28:25 INFO - ##### 18:28:25 INFO - ##### Running run-tests step. 18:28:25 INFO - ##### 18:28:25 INFO - Running pre-action listener: _resource_record_pre_action 18:28:25 INFO - Running main action method: run_tests 18:28:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:28:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:28:25 INFO - Python 2.7.3 18:28:25 INFO - Return code: 0 18:28:25 INFO - grabbing minidump binary from tooltool 18:28: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')]} 18:28:25 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 0x2971d70>, '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 0x2972b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2972fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:28:25 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 18:28:25 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 18:28:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:25 INFO - Return code: 0 18:28:25 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:28:25 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:28:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:28:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:28:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:28:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:28:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost 18:28:25 INFO - Using env: {'DISPLAY': ':0', 18:28:25 INFO - 'HOME': '/home/cltbld', 18:28:25 INFO - 'LANG': 'en_US.UTF-8', 18:28:25 INFO - 'LANGUAGE': 'en_US:en', 18:28:25 INFO - 'LOGNAME': 'cltbld', 18:28:25 INFO - 'MAIL': '/var/mail/cltbld', 18:28:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:28:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:25 INFO - 'MOZ_NO_REMOTE': '1', 18:28:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:28:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:25 INFO - 'NO_EM_RESTART': '1', 18:28:25 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:28:25 INFO - 'PWD': '/builds/slave/test-pgo', 18:28:25 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:28:25 INFO - 'SHELL': '/bin/bash', 18:28:25 INFO - 'SHLVL': '1', 18:28:25 INFO - 'TERM': 'linux', 18:28:25 INFO - 'TMOUT': '86400', 18:28:25 INFO - 'USER': 'cltbld', 18:28:25 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007', 18:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:25 INFO - '_': '/tools/buildbot/bin/python'} 18:28:25 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] with output_timeout 3600 18:28:25 INFO - mozversion INFO | application_buildid: 20151118154243 18:28:25 INFO - mozversion INFO | application_changeset: a1ac4564029cec65bed930613865e0111adc7c7f 18:28:25 INFO - mozversion INFO | application_display_name: Nightly 18:28:25 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:25 INFO - mozversion INFO | application_name: Firefox 18:28:25 INFO - mozversion INFO | application_remotingname: firefox 18:28:25 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:28:25 INFO - mozversion INFO | application_vendor: Mozilla 18:28:25 INFO - mozversion INFO | application_version: 45.0a1 18:28:25 INFO - mozversion INFO | platform_buildid: 20151118154243 18:28:25 INFO - mozversion INFO | platform_changeset: a1ac4564029cec65bed930613865e0111adc7c7f 18:28:25 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:28:25 INFO - mozversion INFO | platform_version: 45.0a1 18:28:25 INFO - 2015-11-18 18:28:25,566 DEBUG : using testdate: 1447900105 18:28:25 INFO - 2015-11-18 18:28:25,566 DEBUG : actual date: 1447900105 18:28:25 INFO - 2015-11-18 18:28:25,574 INFO : starting webserver on 'localhost:35307' 18:28:25 INFO - 2015-11-18 18:28:25,575 INFO : Starting test suite talos-linux64-ix-014 18:28:25 INFO - 2015-11-18 18:28:25,575 INFO : Starting test dromaeo_css 18:28:25 INFO - 2015-11-18 18:28:25,576 DEBUG : operating with platform_type : linux_ 18:28:25 INFO - 2015-11-18 18:28:25,576 INFO : Initialising browser for dromaeo_css test... 18:28:25 INFO - 2015-11-18 18:28:25,586 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpew3Cha/profile http://localhost:35307/getInfo.html 18:28:29 INFO - 2015-11-18 18:28:29,541 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:28:29 INFO - 2015-11-18 18:28:29,542 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:28:29 INFO - 2015-11-18 18:28:29,542 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:28:29 INFO - 2015-11-18 18:28:29,542 DEBUG : BROWSER_OUTPUT: __metrics 18:28:29 INFO - 2015-11-18 18:28:29,739 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:28:30 INFO - 2015-11-18 18:28:30,854 INFO : Browser initialized. 18:28:30 INFO - 2015-11-18 18:28:30,855 INFO : Running cycle 1/1 for dromaeo_css test... 18:28:30 INFO - 2015-11-18 18:28:30,855 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpew3Cha/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:28:42 INFO - 2015-11-18 18:28:42,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 140079104 18:28:42 INFO - 2015-11-18 18:28:42,668 DEBUG : BROWSER_OUTPUT: 18:29:34 INFO - 2015-11-18 18:29:34,336 DEBUG : BROWSER_OUTPUT: 1447900174333 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] 18:31:42 INFO - 2015-11-18 18:31:42,566 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35307/tests/dromaeo/cssquery-ext.html) 18:31:43 INFO - 2015-11-18 18:31:43,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 269373440 18:31:43 INFO - 2015-11-18 18:31:43,812 DEBUG : BROWSER_OUTPUT: 18:34:46 INFO - 2015-11-18 18:34:46,271 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/dromaeo/cssquery-ext.html (next: http://localhost:35307/tests/dromaeo/cssquery-jquery.html) 18:34:46 INFO - 2015-11-18 18:34:46,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 215900160 18:34:46 INFO - 2015-11-18 18:34:46,836 DEBUG : BROWSER_OUTPUT: 18:37:48 INFO - 2015-11-18 18:37:48,658 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35307/tests/dromaeo/cssquery-mootools.html) 18:37:49 INFO - 2015-11-18 18:37:49,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 218763264 18:37:49 INFO - 2015-11-18 18:37:49,225 DEBUG : BROWSER_OUTPUT: 18:40:51 INFO - 2015-11-18 18:40:51,196 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35307/tests/dromaeo/cssquery-prototype.html) 18:40:51 INFO - 2015-11-18 18:40:51,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 195944448 18:40:51 INFO - 2015-11-18 18:40:51,789 DEBUG : BROWSER_OUTPUT: 18:43:54 INFO - 2015-11-18 18:43:54,269 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35307/tests/dromaeo/cssquery-yui.html) 18:43:54 INFO - 2015-11-18 18:43:54,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 203026432 18:43:54 INFO - 2015-11-18 18:43:54,842 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,278 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/dromaeo/cssquery-yui.html (next: http://localhost:35307/tests/dromaeo/cssquery-dojo.html) 18:46:57 INFO - 2015-11-18 18:46:57,529 DEBUG : BROWSER_OUTPUT: RSS: Main: 278204416 18:46:57 INFO - 2015-11-18 18:46:57,529 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,529 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:46:57 INFO - 2015-11-18 18:46:57,529 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:46:57 INFO - 2015-11-18 18:46:57,530 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:46:57 INFO - 2015-11-18 18:46:57,530 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:46:57 INFO - 2015-11-18 18:46:57,530 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3196.4885618301078;3233.3371658809947;3271.656476070014;3287.9835600821993;3288.3094550144474;5137.6403651744395;5241.449647786984;5347.786088556458;5353.116735738603;5367.355917289926;5328.626996110264;5378.193271009351;5451.863703407417;5454.181872719094;5472.781088756445;4792.113459010076;4858.246971719389;4894.241392584155;4972.129877271463;4988.42633097193;4939.160342741725;4989.725565093919;5004.399472063357;5006.899862002758;5032.446430892599;4773.140834649762;4801.279808028824;4878.707277563322;4883.4628190898975;4919.458859525449;4822.927656085161;4842.636802239811;4902.460729319772;4918.458969513351;4935.703857768545;4881.682690625098;4902.460729319772;4907.362042934431;4909.214525675875;4922.360093187863;5036.974815125937;5080.79676812927;5105.770240339157;5192.402873669501;5265.947340526623;5761.3950535194035;5772.163036359746;5781.653100813965;5783.913241301384;5800.9129863052085;5817.7963771267805;5819.592628516006;5823.388544202882;5823.5341172706085;5835.620684655484;5229.816956406546;5263.3947096083675;5334.946650533522;5378.91931621026;5433.293671822677;5764.74058667361;5777.306723193244;5802.33273173582;5810.680412577264;5838.4453476919625;5616.943830561724;5619.157126430986;5630.211770352154;5631.267935168484;5632.746526406239;5537.307836520435;5625.831225063254;5663.575231857545;5713.400092990176;5870.530357571384;5702.429757024264;5714.114312281603;5714.485696287271;5721.485066344049;5732.455416735404;5701.515371193412;5705.229793977843;5716.485516303553;5717.342505611825;5720.027595308808;4322.027543802619;4398.23030969579;4398.934015989763;4485.259932111165;4486.035502367007;3966.742161759534;3985.103351745834;3987.2932413188646;3993.5008123984503;4014.437978682987;5382.434844341287;5408.810691625853;5414.729263536808;5419.783208671688;5441.156620723794;5675.574331925122;5733.627314224562;5806.82579522615;5807.12893066035;5884.9705751470965;5401.270828438111;5401.513863752281;5425.674459532441;5426.267453893753;5427.104527752956;5040.067587496252;5050.04049230645;5051.1413059779925;5054.620903432257;5110.846674599694;5757;5776.653400795965;5847.590668653153;5881;5896.351401345849;5771;5787.884242315215;5796.507296879644;5805.593608447368;5820.417958204146;5698.430156984434;5983.252093488314;5995.340512543529;6017.939820601738;6025.548083893813;5604.551635869039;5610.7194640268635;5690.402507736793;5690.687012214368;5735.627184233178;5785.4214578541805;5799.507041901317;5804.767809287581;5806.390329015561;5810.854728631818;5426.755795989111;5563.221149039056;5638.633488823213;5654.434556544313;5656.37779844225;5494.835154945358;5568.136938774496;5579.804706835242;5580.107182850725;5625;5559.33288005442;5593.8321850344555;5596.440355964371;5597.468240517144;5607.747651355618;5436.45635436469;5537.418571049981;5584.218209450762;5714.628549144292;5744.195812586325;5424.294841670558;5439.945600543944;5440.156775699773;5447.754851031792;5448.972755136199 18:46:57 INFO - 2015-11-18 18:46:57,531 DEBUG : BROWSER_OUTPUT: |1;ext.html;555.017618814698;602.0367412140573;602.5360472436223;604.8578584032749;607.1257389359322;30736.231594210156;39400.60599394009;39652.80173599135;39675.80162099193;39752.602473975254;41515;41817.16365672685;41994.79002604983;42044.789776051075;42097.57902420975;7522.887156692654;7661.770146895602;8668.696595619138;8841.469511829278;8849.646014159425;10397;10657.147428205764;10665;10666.893331066707;10669.146468282557;10331.173506119492;10331.948340258288;10397.480125993708;10402.63590774323;10408;7706.113796913371;7877;7888.2506161914525;7890.644920978512;10248.538815753273;7553.320201181865;10304.433256170907;10609.15126789855;10618.309809862412;10626.36241825493;39067.60932390668;39569.20861582781;39593.20813583741;39609;39641.60358396408;3405.9659403405894;3445.810480423576;3543.7696549724187;3548.6451354864566;3748.9437658435145;29563.408731825242;29712.257193570116;30125;30428.239294017825;30546.08361749151;3491.6159222485508;3520.77114987525;3537.663921927413;3570.0896271450874;3622.4385220290897;274420;274647;275055;275896.6205168981;276318;54134.72932635312;55114.448855511735;55138.448615514135;56925;57675.71162144246;57426.7128664354;57522;57830.13254801181;57843.42156578464;57881.71059144761;14473.493427729978;14478.855211447752;14500.927495362666;14550.27248637564;14610.26948652563;11854.585089521825;11933.462994165284;11947.940260298643;11964.880351196376;12089.697757556132;18880.055997200085;18908.43274701761;18942.337018204296;19011.61976760457;19054.714179287323;7318.414526837841;7533.623318834037;7603.163651998276;7664.156942736295;7729.884051739228;935.5930170375908;956.4978386347224;960.3338208208752;984.3503287829998;1019.9439030853374;28642.570361444596;28838.855805721258;28975.85512072426;29244.268893277836;29350;1054.4569546683465;1061.5488417422564;1104.5195340027115;1109.14595761265;1123.5168877382641;14870.479533216285;15428.151451670266;15471.458498952483;15478.380864765284;15746.9212653936;325064;325882;326773;327320;329119;52365.2145217822;52749.7362513185;52967;53014.73492632512;53027.20459193115;1336.064754671753;1336.432016393029;1338.2572672167041;1351.148776270943;1364.9454021839015;28220.576691349237;28575.285617858888;28788.56590402808;29276.41447171002;29323.7067629321;45543;45957.310640339754;46140.7692961533;46238.537614624765;46329.305060423445;303418;304709;306274;308277;308419;28142.18578142251;28478.145655630364;31350.216244594067;32205;32218.677813222504;40523;40604;41629;41768.791156044026;41854.16291674088;33795.66204337925;34765;34983;35037.299254015285;35325.82337088298;28029.719702803526;30951.380972380877;34660.133496662784;34842.65157348394;35887.82056089807;31825;32039;32464.51303230406;32626.51060234146;33352.49971250482;30448.391032179676;31615.36769264556;31904.6809531911;31972.36055278928;32381.838090809397;23603.29190124299;23830.285091447284;23835.284941451784;23984.28047158588;24020.39949001289 18:46:57 INFO - 2015-11-18 18:46:57,535 DEBUG : BROWSER_OUTPUT: |2;jquery.html;354.91487510873634;358.7829363235241;359.71762166699136;360.8304097074376;362.91290090378317;14251.50119745809;14297.142171469693;16387.344506219753;17184.398546050892;18143.54641133974;11758.941205293962;12037.638870833867;16181.19094045296;16548.66902661946;16663.41678041269;4561.452625684922;4670.462896766864;4675.11172877154;4676.649251306166;4678.625709943213;3614.8734794282204;3716.5319698719127;4636.327732478789;4664.603508701747;5004.699718016931;5028.673136246132;5066.797328106872;5083.923741143886;5084.567811736007;5086.033653605821;4937.580305674004;5053.595712343041;5054.898902021976;5055.014272216919;5064.063148317573;4382.868513944587;4382.978085109586;4383.60547550722;4384.605385515287;4385.736855788631;13502.324883755773;13742.793858092136;13743.725125497534;13753.79369309461;13940.512082077079;2070.7471979452516;2076.8234700050516;2097.2869224463757;2126.0432805237706;2127.8936053197276;10123.392596444237;10133.543990520444;10165.440900750456;10280.331778434453;10290.7427314317;1732.2378153612497;1744.7470116832988;1757.3409971260778;1786.4908501077261;1796.8987080437541;58198;59092.704536477475;59726.701366493326;60141.6992915037;60441.6977915112;6884.586924784529;7040.295970403021;7072.8939065914055;7086.149662040588;7127.89308160378;8542.530160841088;8548.48709077445;8557.144285571392;8562.443441176303;8567.52878591671;5602.187682785973;5780.046292361797;5820.039693450533;5829.154772557956;5835.883282334415;5918.053111502225;5949.6430214187285;5977.043673012297;5988.730507127189;6011.579189456696;3242.092214179986;3285.5400243965864;3304.801711897294;3318.5022246663193;3321.667833216659;1126.532489017065;1155.3536210899604;1157.8147496400536;1170.7716995185856;1210.285931300537;472.16191260512863;481.2396413666399;492.45583630088237;495.4401526275358;498.6559274100859;11885.346305953082;12081.237449549648;12136.33250171249;12197.51209951592;12198.75602487963;471.4507598647537;497.9402471703419;511.06220086141604;513.4249640402772;521.817363922624;6224;6288.842778930563;6588.769393071219;6643.435307998874;6670.299073736166;522108;524976;526077;527294;531071;71762;71997;73798.63100684462;74685;75417.62291188509;566.1705601294124;572.6106247751486;572.7909313100688;573.3778849947853;575.3010092737344;10504.579816807242;10908.34549927007;11199.832002519806;11253.7749245013;11255.099592032782;31817.840910795298;31848.840755796075;31958.36083278368;32250.677493225707;32849.178770530925;39746.80126599348;39851;39874;40537;40603.79698101491;6768.932310676831;7001.544899581511;7034.788956331318;7045.365917067488;7050.048243487268;12318.938405307916;12644.747105057664;12653.87346126527;12729.745405092031;12839.688856006009;10600.363978161335;10851;11043.116550676088;11064.28082174656;11204;10737.946310268399;10829.971152740478;10995.34027958325;11019.173561983043;11038;10988.725281868017;11262.267952583055;11262.267952583055;11372.715682108013;11374.886251137383;6830.351116643911;6889.311068893271;6981.476389270723;6983.301669832976;6987.755428559976;10687.30532515384;10788.514516846604;10884.891151088388;11024.338539687644;11153.888461115284 18:46:57 INFO - 2015-11-18 18:46:57,536 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1317.965397163227;1657.6767530331583;1695.3049249807582;1711.1925964728478;1722.7932648082233;6658.833529161768;7062.929370706292;7299.63501824908;7321.743738969137;7328.596927169004;7148.96425517873;7331;7347.853042939151;7354.48518603698;7362.8527429451515;1902.8858268503866;1934.2843148035247;2181.4873504726415;2204.6582779669097;2257.9096836126537;2242.394553470562;2246.213825161197;2249.201533455616;2250.639897616382;2251.223327951854;2219.533897881447;2314.6528020796845;2314.687517185184;2316.096722278313;2320.895559699809;1996.7004949257582;2048.2523878784286;2245.315178870436;2281.8402711810013;2284.2119468783244;2266.4447210433386;2276.6243569811127;2299.7930186283147;2305.4697419593585;2339.9181028664084;7450.478466507347;7481.8503629927645;7518.28576285252;7519.6240187990925;7526.285002924714;4600.194965880991;4665;4675;4675.205215113405;4706.482286948433;3120;3126.31221131353;3160.4153231652217;3161.96838031619;3254.0400581828417;1019.6686077024997;1039.7868436970402;1053.0995998421392;1082.1018554599664;1091.3997301484153;9949.701508954742;10026.799464010679;10029.79940401188;10042.397456152654;10060.647877324258;4197.391378250137;4253.97873010633;4284.635805956466;4357.9128417431475;4447.466304043471;8394.916050839414;8521.403501754941;8555.786105347415;8580.914190858011;8581.485110893242;4734.100520901018;4742.288656701522;4762.642801789811;4765.070811191785;4788.401449818773;4364.170807546556;4373.059792144641;4402.537733537996;4402.713823601455;4411.228035093845;3499.16020155166;3514.367413865478;3522.9647703522637;3530.0998245447245;3548.27260411615;1068.9625863094755;1072.2012100984816;1073.4042606353394;1080.475969154959;1095.1731442760665;1045.0176833776227;1316.7366526694507;1319.2018828608745;1321.9074664773373;1353.587155917435;6339.778107766206;7044;7218.711251549879;7221.277872212737;7229.349358557755;701.7017767448916;709.4324540367685;724.1925253342486;726.785598248518;730.171255624859;5643.209950606918;5663.716814159276;5718.857028574319;5737.770489180386;5903.144044113579;9033.683821066232;9196.540172991323;9227.35408521397;9237.030111838294;9266.768330791785;8783;8895.110488951183;9130.589123489326;9145.725628231165;9157.084291570924;7014;7093.751718689821;7105.573665580082;7163.570185788765;7219.675114619749;6925.515213935178;6957.686904089227;7050.153981522045;7072;7100.751473698396;7776.988991431304;8034.799130021563;8104.392170587112;8142.51144931306;8147.551884646401;8031.357491400557;8166.959165204136;8176.67293308261;8180.631871565913;8185.590720464072;6809.319068093151;6903;6907.6891539881835;6911.205211400753;6921.1002569665625;7328.303811137934;7402.148752893486;7427.25727427253;7430.108386993379;7440.181580026085;6942.097527321416;7004.054452649031;7048.471364647569;7055.541389809646;7055.929440705732;6676.49926255523;6765.25582185969;6851.794446166623;6956.304369563003;6976.7558135465015;6172.259328880384;6905.620190889549;6957.860842783217;6987.895181572178;7033.542819716702;5725.742341594555;6222.28443728977;6366.745330186741;6367.649779262185;6369.9681501592195;6407.583507072025;6462.741490340334;6465.741370345134;6470.417662410406;6474.384933431316 18:46:57 INFO - 2015-11-18 18:46:57,537 DEBUG : BROWSER_OUTPUT: |4;prototype.html;337.57634169117756;342.4879804691986;343.5448031358451;344.42824910648324;354.81904228843297;5935.169076329317;6025.9096113558335;6099.146119543262;6106.053561697939;6154.199954005984;5858.970705146468;6008.789692360769;6048.879022419549;6050.60671056381;6054.122152287927;4669.369635099269;4674.485806561275;4684.953150468486;4685.83599574015;4686.742229177394;4446.066326071529;4512.954870451286;4580.335851301559;4652.348671186037;4675.438947326325;4609.170349337118;4618;4622.07558488302;4640.559146881041;4649;4636.721796692209;4641.187792136379;4643.094596553673;4643.558861908147;4662.72023678577;4095.1195492968855;4104.56902025286;4107.240160570274;4107.876763697094;4119.217348703737;5608.551315894719;5707.1724599933195;5712.542996560265;5718.399568045337;5733.569982251306;5424.701641409721;5430.864228394283;5431.21247419122;5433.1850222466555;5437.13005919055;6152.938470615281;6375.394337537927;6387.616742995435;6397.8400539986405;6402.711877965503;4435.911281774379;4471.39636149119;4496.235639941186;4503.594676479132;4506.985928166169;6521.15225020749;6562.114114594564;6592.439642630334;6593.86812263752;6599;4659.627229821606;4665.346851440801;4668.183067963093;4670.9766451167525;4672.415948006499;4652.13935421948;4673.252279635242;4714.717116972993;4715.622750179978;4729.905401891943;5395;5395.190721391823;5396.487333703292;5402.783888644411;5403.83788486346;6495.415412612888;6495.8700825983215;6496.707648155844;6498.480121590262;6500.284968653445;1663.8668906487694;1692.2976964559816;1692.3907393338388;1695.474402935094;1695.7625932369479;1080.8054550180887;1092.8633920759905;1098.1544210957518;1154.6362895687998;1172.1209093180114;6002.639841609517;6129.509639228941;6129.724162412613;6130.570860039755;6142.846428839315;6615.66921653925;6629.5359324846795;6636.402723754885;6637.402633762985;6638;5972.761089556369;6131.877362452726;6135.478484328793;6135.5398345124295;6142.262928448526;3574.8212589370946;3610.5667319921777;3611.6749492545796;3612.223371975038;3612.349777040158;6843.794686159423;6850.725970961105;6852.383285504229;6856.897146542806;6861.691223894937;6647.734090636417;6771.966140169267;6788.049673045777;6791.524593278522;6799.354061364163;6846.349596788297;6848.554843935129;6853.348931851466;6862.759803406957;6862.965685171542;6469.417752402306;6577.276499585138;6608.669566521751;6617.106690596708;6625.4699624029;6665.466762658879;6668.83327916806;6673.364021211497;6676.83307917306;6683.8997415039785;6951.965240173767;6970.093887794555;6973.930260697531;6981.79054628362;6990.930090699231;4482.282834746425;4574.176648203355;4578.4963653997365;4580.496145423934;4584.197765390976;6708.463322934056;6708.496862735216;6712.966435167793;6716.731330746716;6718.7312507499155;6508.414242718178;6518.73925042993;6522.608643481406;6525.184351956005;6529.281779004211;6423.678816059271;6537.836554086186;6549.934500654933;6550.901736473861;6557.868842623026;6419.390157934989;6430.967845160744;6437.549371543947;6439.3238709934785;6439.645819479973;6588.341165883373;6589.571677840925;6590.505712071518;6592.868142637025;6595.307492713388;6282.371762823681;6421.7110230038825;6422.550421470452;6430.067640192053;6435.935640643534 18:46:57 INFO - 2015-11-18 18:46:57,539 DEBUG : BROWSER_OUTPUT: |5;yui.html;745.612281613561;762.2529920677738;831.1688311688312;833.683200383854;873.4715497124236;14039.929800350998;14199.50301739438;14215.289235538221;14772.636378179493;15124.773128403056;14669.339879705412;14753;14766.704665906702;14772.335244913978;14807.407703691839;675.3246753246754;808.923152300532;820.7578764264556;820.9055958564752;821.0352835418389;664.2195420381033;811.2171754257141;819.6885183630212;820.327331588098;820.7496713502379;819.9139090395482;851.6040041380757;853.6116067189414;855.5636625321068;855.9186877246651;694.8853439182528;700.1947760075902;834.2491757418311;840.861257892447;849.0617867256685;607.9361667024987;611.461913516103;618.279704144671;618.9319174890759;619.6437048697018;13031.739365212643;13132;13144.934275328562;13156.342182890818;13170.86829131706;10070.395776253376;10158.695239142837;10178.287519873611;10596.523156457977;10662.360258384522;9767.413955162641;10079.5464204111;10289.279750417474;10292.433916134609;10376.16990640747;3309.652486488932;3317.701406873393;3320.9833950830334;3334.3998080345527;3361.8991430257133;40098.19803603941;47562.7621861892;47668.28497572539;47682.523174768154;47796.522034779555;7811.609419529114;7850.136484986647;7932;8031.317338026216;8058.274755272054;12204.877951220551;12216.816747748613;12218.633440996784;12258.019358451302;12273.324967126893;6880.965595171992;6955.89566156508;7064.116985376828;7126.928730712731;7251.093613298338;4284.785760712014;4314.913701726011;4332.805023773938;4370.2352088384405;4375.168717943644;3084.953725694116;3117.8596963136715;3150.5431712401573;3153.684631536874;3158.4156930967843;498.99002019959397;505.4591587001873;511.5217271850757;513.7816428018077;513.7944822071202;577.8670905691714;607.6718571971174;624.4286477872696;670.4368330602317;679.3919442099392;9193.218576421079;9231.815363692689;9280.628774849065;9488.905110948803;9755.951220243995;570.9494530064701;625.7528276330823;662.1888186970922;695.1415002471944;707.3845754193782;4115.259253334369;4363.672724545671;4367.344898265285;4410.562762843071;4424.535423780521;60191.699041504515;60715.69642151849;60722.39277607256;60725;60736.69631651814;51147;51628.483715162365;51737.741311293204;51761.22358164631;52819.735901320244;46126.769366152956;47309.52690473051;47827.52172478231;48421;48922.04311827487;8781.341399394943;8834.381593288408;9192.724218273463;9217.493037882981;9305.627774888928;36605.63394366022;37728.62271377251;37993.62006379901;38195.42706859344;38353.4246986301;43940.78029609832;45039.774801125786;47260.7636961813;47845.28232076591;47889;8344.791380215544;8401.453905496122;8475.982882054193;8645.394822362374;8662.956685216534;12397;12406.3201820564;12586.748265034465;12694.428750706056;12831;11459.140564457532;11843.11176661771;11908.875727825785;12231.633051008128;12553;11585;12060.75878482443;12447.439865206268;12452.128351015343;12460.252384856938;11674.474648641002;11905.821412678644;12205.816912746139;12411.131220814455;12468.064895133082;10096.747581310527;10455.68632941013;10824.945875270574;11041.944790275997;11048.723781905517;9512.762180945532;9941.353812002197;9961.015593762553;10069.89930100719;10274.486275686335 18:46:57 INFO - 2015-11-18 18:46:57,539 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:46:57 INFO - 2015-11-18 18:46:57,539 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:46:57 INFO - 2015-11-18 18:46:57,539 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1037 18:46:57 INFO - 2015-11-18 18:46:57,539 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:46:57 INFO - 2015-11-18 18:46:57,539 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901217526__endTimestamp 18:46:57 INFO - 2015-11-18 18:46:57,539 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5333.78 Median:5494.84 stddev:576.78 (10.5%) stddev-sans-first:553.64 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: Values: 3196.5 3233.3 3271.7 3288.0 3288.3 5137.6 5241.4 5347.8 5353.1 5367.4 5328.6 5378.2 5451.9 5454.2 5472.8 4792.1 4858.2 4894.2 4972.1 4988.4 4939.2 4989.7 5004.4 5006.9 5032.4 4773.1 4801.3 4878.7 4883.5 4919.5 4822.9 4842.6 4902.5 4918.5 4935.7 4881.7 4902.5 4907.4 4909.2 4922.4 5037.0 5080.8 5105.8 5192.4 5265.9 5761.4 5772.2 5781.7 5783.9 5800.9 5817.8 5819.6 5823.4 5823.5 5835.6 5229.8 5263.4 5334.9 5378.9 5433.3 5764.7 5777.3 5802.3 5810.7 5838.4 5616.9 5619.2 5630.2 5631.3 5632.7 5537.3 5625.8 5663.6 5713.4 5870.5 5702.4 5714.1 5714.5 5721.5 5732.5 5701.5 5705.2 5716.5 5717.3 5720.0 4322.0 4398.2 4398.9 4485.3 4486.0 3966.7 3985.1 3987.3 3993.5 4014.4 5382.4 5408.8 5414.7 5419.8 5441.2 5675.6 5733.6 5806.8 5807.1 5885.0 5401.3 5401.5 5425.7 5426.3 5427.1 5040.1 5050.0 5051.1 5054.6 5110.8 5757.0 5776.7 5847.6 5881.0 5896.4 5771.0 5787.9 5796.5 5805.6 5820.4 5698.4 5983.3 5995.3 6017.9 6025.5 5604.6 5610.7 5690.4 5690.7 5735.6 5785.4 5799.5 5804.8 5806.4 5810.9 5426.8 5563.2 5638.6 5654.4 5656.4 5494.8 5568.1 5579.8 5580.1 5625.0 5559.3 5593.8 5596.4 5597.5 5607.7 5436.5 5537.4 5584.2 5714.6 5744.2 5424.3 5439.9 5440.2 5447.8 5449.0 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46730.19 Median:32423.18 stddev:79366.59 (244.8%) stddev-sans-first:79513.51 18:46:57 INFO - 2015-11-18 18:46:57,540 DEBUG : BROWSER_OUTPUT: Values: 555.0 602.0 602.5 604.9 607.1 30736.2 39400.6 39652.8 39675.8 39752.6 41515.0 41817.2 41994.8 42044.8 42097.6 7522.9 7661.8 8668.7 8841.5 8849.6 10397.0 10657.1 10665.0 10666.9 10669.1 10331.2 10331.9 10397.5 10402.6 10408.0 7706.1 7877.0 7888.3 7890.6 10248.5 7553.3 10304.4 10609.2 10618.3 10626.4 39067.6 39569.2 39593.2 39609.0 39641.6 3406.0 3445.8 3543.8 3548.6 3748.9 29563.4 29712.3 30125.0 30428.2 30546.1 3491.6 3520.8 3537.7 3570.1 3622.4 274420.0 274647.0 275055.0 275896.6 276318.0 54134.7 55114.4 55138.4 56925.0 57675.7 57426.7 57522.0 57830.1 57843.4 57881.7 14473.5 14478.9 14500.9 14550.3 14610.3 11854.6 11933.5 11947.9 11964.9 12089.7 18880.1 18908.4 18942.3 19011.6 19054.7 7318.4 7533.6 7603.2 7664.2 7729.9 935.6 956.5 960.3 984.4 1019.9 28642.6 28838.9 28975.9 29244.3 29350.0 1054.5 1061.5 1104.5 1109.1 1123.5 14870.5 15428.2 15471.5 15478.4 15746.9 325064.0 325882.0 326773.0 327320.0 329119.0 52365.2 52749.7 52967.0 53014.7 53027.2 1336.1 1336.4 1338.3 1351.1 1364.9 28220.6 28575.3 28788.6 29276.4 29323.7 45543.0 45957.3 46140.8 46238.5 46329.3 303418.0 304709.0 306274.0 308277.0 308419.0 28142.2 28478.1 31350.2 32205.0 32218.7 40523.0 40604.0 41629.0 41768.8 41854.2 33795.7 34765.0 34983.0 35037.3 35325.8 28029.7 30951.4 34660.1 34842.7 35887.8 31825.0 32039.0 32464.5 32626.5 33352.5 30448.4 31615.4 31904.7 31972.4 32381.8 23603.3 23830.3 23835.3 23984.3 24020.4 18:46:57 INFO - 2015-11-18 18:46:57,541 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,541 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26319.41 Median:40570.40 stddev:86167.81 (212.4%) stddev-sans-first:86387.48 18:46:57 INFO - 2015-11-18 18:46:57,542 DEBUG : BROWSER_OUTPUT: Values: 354.9 358.8 359.7 360.8 362.9 14251.5 14297.1 16387.3 17184.4 18143.5 11758.9 12037.6 16181.2 16548.7 16663.4 4561.5 4670.5 4675.1 4676.6 4678.6 3614.9 3716.5 4636.3 4664.6 5004.7 5028.7 5066.8 5083.9 5084.6 5086.0 4937.6 5053.6 5054.9 5055.0 5064.1 4382.9 4383.0 4383.6 4384.6 4385.7 13502.3 13742.8 13743.7 13753.8 13940.5 2070.7 2076.8 2097.3 2126.0 2127.9 10123.4 10133.5 10165.4 10280.3 10290.7 1732.2 1744.7 1757.3 1786.5 1796.9 58198.0 59092.7 59726.7 60141.7 60441.7 6884.6 7040.3 7072.9 7086.1 7127.9 8542.5 8548.5 8557.1 8562.4 8567.5 5602.2 5780.0 5820.0 5829.2 5835.9 5918.1 5949.6 5977.0 5988.7 6011.6 3242.1 3285.5 3304.8 3318.5 3321.7 1126.5 1155.4 1157.8 1170.8 1210.3 472.2 481.2 492.5 495.4 498.7 11885.3 12081.2 12136.3 12197.5 12198.8 471.5 497.9 511.1 513.4 521.8 6224.0 6288.8 6588.8 6643.4 6670.3 522108.0 524976.0 526077.0 527294.0 531071.0 71762.0 71997.0 73798.6 74685.0 75417.6 566.2 572.6 572.8 573.4 575.3 10504.6 10908.3 11199.8 11253.8 11255.1 31817.8 31848.8 31958.4 32250.7 32849.2 39746.8 39851.0 39874.0 40537.0 40603.8 6768.9 7001.5 7034.8 7045.4 7050.0 12318.9 12644.7 12653.9 12729.7 12839.7 10600.4 10851.0 11043.1 11064.3 11204.0 10737.9 10830.0 10995.3 11019.2 11038.0 10988.7 11262.3 11262.3 11372.7 11374.9 6830.4 6889.3 6981.5 6983.3 6987.8 10687.3 10788.5 10884.9 11024.3 11153.9 18:46:57 INFO - 2015-11-18 18:46:57,542 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,542 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5307.96 Median:6388.78 stddev:2697.38 (42.2%) stddev-sans-first:2688.27 18:46:57 INFO - 2015-11-18 18:46:57,542 DEBUG : BROWSER_OUTPUT: Values: 1318.0 1657.7 1695.3 1711.2 1722.8 6658.8 7062.9 7299.6 7321.7 7328.6 7149.0 7331.0 7347.9 7354.5 7362.9 1902.9 1934.3 2181.5 2204.7 2257.9 2242.4 2246.2 2249.2 2250.6 2251.2 2219.5 2314.7 2314.7 2316.1 2320.9 1996.7 2048.3 2245.3 2281.8 2284.2 2266.4 2276.6 2299.8 2305.5 2339.9 7450.5 7481.9 7518.3 7519.6 7526.3 4600.2 4665.0 4675.0 4675.2 4706.5 3120.0 3126.3 3160.4 3162.0 3254.0 1019.7 1039.8 1053.1 1082.1 1091.4 9949.7 10026.8 10029.8 10042.4 10060.6 4197.4 4254.0 4284.6 4357.9 4447.5 8394.9 8521.4 8555.8 8580.9 8581.5 4734.1 4742.3 4762.6 4765.1 4788.4 4364.2 4373.1 4402.5 4402.7 4411.2 3499.2 3514.4 3523.0 3530.1 3548.3 1069.0 1072.2 1073.4 1080.5 1095.2 1045.0 1316.7 1319.2 1321.9 1353.6 6339.8 7044.0 7218.7 7221.3 7229.3 701.7 709.4 724.2 726.8 730.2 5643.2 5663.7 5718.9 5737.8 5903.1 9033.7 9196.5 9227.4 9237.0 9266.8 8783.0 8895.1 9130.6 9145.7 9157.1 7014.0 7093.8 7105.6 7163.6 7219.7 6925.5 6957.7 7050.2 7072.0 7100.8 7777.0 8034.8 8104.4 8142.5 8147.6 8031.4 8167.0 8176.7 8180.6 8185.6 6809.3 6903.0 6907.7 6911.2 6921.1 7328.3 7402.1 7427.3 7430.1 7440.2 6942.1 7004.1 7048.5 7055.5 7055.9 6676.5 6765.3 6851.8 6956.3 6976.8 6172.3 6905.6 6957.9 6987.9 7033.5 5725.7 6222.3 6366.7 6367.6 6370.0 6407.6 6462.7 6465.7 6470.4 6474.4 18:46:57 INFO - 2015-11-18 18:46:57,542 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,542 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5403.45 Median:6117.78 stddev:1618.05 (26.4%) stddev-sans-first:1577.28 18:46:57 INFO - 2015-11-18 18:46:57,542 DEBUG : BROWSER_OUTPUT: Values: 337.6 342.5 343.5 344.4 354.8 5935.2 6025.9 6099.1 6106.1 6154.2 5859.0 6008.8 6048.9 6050.6 6054.1 4669.4 4674.5 4685.0 4685.8 4686.7 4446.1 4513.0 4580.3 4652.3 4675.4 4609.2 4618.0 4622.1 4640.6 4649.0 4636.7 4641.2 4643.1 4643.6 4662.7 4095.1 4104.6 4107.2 4107.9 4119.2 5608.6 5707.2 5712.5 5718.4 5733.6 5424.7 5430.9 5431.2 5433.2 5437.1 6152.9 6375.4 6387.6 6397.8 6402.7 4435.9 4471.4 4496.2 4503.6 4507.0 6521.2 6562.1 6592.4 6593.9 6599.0 4659.6 4665.3 4668.2 4671.0 4672.4 4652.1 4673.3 4714.7 4715.6 4729.9 5395.0 5395.2 5396.5 5402.8 5403.8 6495.4 6495.9 6496.7 6498.5 6500.3 1663.9 1692.3 1692.4 1695.5 1695.8 1080.8 1092.9 1098.2 1154.6 1172.1 6002.6 6129.5 6129.7 6130.6 6142.8 6615.7 6629.5 6636.4 6637.4 6638.0 5972.8 6131.9 6135.5 6135.5 6142.3 3574.8 3610.6 3611.7 3612.2 3612.3 6843.8 6850.7 6852.4 6856.9 6861.7 6647.7 6772.0 6788.0 6791.5 6799.4 6846.3 6848.6 6853.3 6862.8 6863.0 6469.4 6577.3 6608.7 6617.1 6625.5 6665.5 6668.8 6673.4 6676.8 6683.9 6952.0 6970.1 6973.9 6981.8 6990.9 4482.3 4574.2 4578.5 4580.5 4584.2 6708.5 6708.5 6713.0 6716.7 6718.7 6508.4 6518.7 6522.6 6525.2 6529.3 6423.7 6537.8 6549.9 6550.9 6557.9 6419.4 6431.0 6437.5 6439.3 6439.6 6588.3 6589.6 6590.5 6592.9 6595.3 6282.4 6421.7 6422.6 6430.1 6435.9 18:46:57 INFO - 2015-11-18 18:46:57,543 DEBUG : BROWSER_OUTPUT: 18:46:57 INFO - 2015-11-18 18:46:57,543 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13851.29 Median:47812.02 stddev:16413.49 (34.3%) stddev-sans-first:16430.03 18:46:57 INFO - 2015-11-18 18:46:57,544 DEBUG : BROWSER_OUTPUT: Values: 745.6 762.3 831.2 833.7 873.5 14039.9 14199.5 14215.3 14772.6 15124.8 14669.3 14753.0 14766.7 14772.3 14807.4 675.3 808.9 820.8 820.9 821.0 664.2 811.2 819.7 820.3 820.7 819.9 851.6 853.6 855.6 855.9 694.9 700.2 834.2 840.9 849.1 607.9 611.5 618.3 618.9 619.6 13031.7 13132.0 13144.9 13156.3 13170.9 10070.4 10158.7 10178.3 10596.5 10662.4 9767.4 10079.5 10289.3 10292.4 10376.2 3309.7 3317.7 3321.0 3334.4 3361.9 40098.2 47562.8 47668.3 47682.5 47796.5 7811.6 7850.1 7932.0 8031.3 8058.3 12204.9 12216.8 12218.6 12258.0 12273.3 6881.0 6955.9 7064.1 7126.9 7251.1 4284.8 4314.9 4332.8 4370.2 4375.2 3085.0 3117.9 3150.5 3153.7 3158.4 499.0 505.5 511.5 513.8 513.8 577.9 607.7 624.4 670.4 679.4 9193.2 9231.8 9280.6 9488.9 9756.0 570.9 625.8 662.2 695.1 707.4 4115.3 4363.7 4367.3 4410.6 4424.5 60191.7 60715.7 60722.4 60725.0 60736.7 51147.0 51628.5 51737.7 51761.2 52819.7 46126.8 47309.5 47827.5 48421.0 48922.0 8781.3 8834.4 9192.7 9217.5 9305.6 36605.6 37728.6 37993.6 38195.4 38353.4 43940.8 45039.8 47260.8 47845.3 47889.0 8344.8 8401.5 8476.0 8645.4 8663.0 12397.0 12406.3 12586.7 12694.4 12831.0 11459.1 11843.1 11908.9 12231.6 12553.0 11585.0 12060.8 12447.4 12452.1 12460.3 11674.5 11905.8 12205.8 12411.1 12468.1 10096.7 10455.7 10824.9 11041.9 11048.7 9512.8 9941.4 9961.0 10069.9 10274.5 18:46:57 INFO - 2015-11-18 18:46:57,544 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:46:57 INFO - 2015-11-18 18:46:57,544 DEBUG : BROWSER_OUTPUT: 18:46:58 INFO - 2015-11-18 18:46:58,197 INFO : Browser exited with error code: 0 18:46:58 INFO - 2015-11-18 18:46:58,216 INFO : Completed test dromaeo_css (00:18:32) 18:46:58 INFO - 2015-11-18 18:46:58,216 INFO : Starting test kraken 18:46:58 INFO - 2015-11-18 18:46:58,216 DEBUG : operating with platform_type : linux_ 18:46:58 INFO - 2015-11-18 18:46:58,216 INFO : Initialising browser for kraken test... 18:46:58 INFO - 2015-11-18 18:46:58,222 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp86ghcA/profile http://localhost:35307/getInfo.html 18:47:02 INFO - 2015-11-18 18:47:02,186 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:47:02 INFO - 2015-11-18 18:47:02,186 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:47:02 INFO - 2015-11-18 18:47:02,186 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:47:02 INFO - 2015-11-18 18:47:02,186 DEBUG : BROWSER_OUTPUT: __metrics 18:47:02 INFO - 2015-11-18 18:47:02,307 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:47:03 INFO - 2015-11-18 18:47:03,541 INFO : Browser initialized. 18:47:03 INFO - 2015-11-18 18:47:03,542 INFO : Running cycle 1/1 for kraken test... 18:47:03 INFO - 2015-11-18 18:47:03,542 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp86ghcA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:47:15 INFO - 2015-11-18 18:47:15,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 140054528 18:47:15 INFO - 2015-11-18 18:47:15,294 DEBUG : BROWSER_OUTPUT: 18:47:17 INFO - 2015-11-18 18:47:17,728 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35307/tests/kraken/driver.html?testName=audio-beat-detection) 18:47:18 INFO - 2015-11-18 18:47:18,315 DEBUG : BROWSER_OUTPUT: RSS: Main: 179916800 18:47:18 INFO - 2015-11-18 18:47:18,315 DEBUG : BROWSER_OUTPUT: 18:47:20 INFO - 2015-11-18 18:47:20,917 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35307/tests/kraken/driver.html?testName=audio-dft) 18:47:21 INFO - 2015-11-18 18:47:21,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 168861696 18:47:21 INFO - 2015-11-18 18:47:21,490 DEBUG : BROWSER_OUTPUT: 18:47:24 INFO - 2015-11-18 18:47:24,360 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35307/tests/kraken/driver.html?testName=audio-fft) 18:47:24 INFO - 2015-11-18 18:47:24,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 150777856 18:47:24 INFO - 2015-11-18 18:47:24,922 DEBUG : BROWSER_OUTPUT: 18:47:26 INFO - 2015-11-18 18:47:26,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35307/tests/kraken/driver.html?testName=audio-oscillator) 18:47:27 INFO - 2015-11-18 18:47:27,445 DEBUG : BROWSER_OUTPUT: RSS: Main: 155164672 18:47:27 INFO - 2015-11-18 18:47:27,445 DEBUG : BROWSER_OUTPUT: 18:47:29 INFO - 2015-11-18 18:47:29,607 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35307/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:30 INFO - 2015-11-18 18:47:30,173 DEBUG : BROWSER_OUTPUT: RSS: Main: 157343744 18:47:30 INFO - 2015-11-18 18:47:30,173 DEBUG : BROWSER_OUTPUT: 18:47:33 INFO - 2015-11-18 18:47:33,072 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35307/tests/kraken/driver.html?testName=imaging-darkroom) 18:47:33 INFO - 2015-11-18 18:47:33,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 156868608 18:47:33 INFO - 2015-11-18 18:47:33,641 DEBUG : BROWSER_OUTPUT: 18:47:37 INFO - 2015-11-18 18:47:37,003 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35307/tests/kraken/driver.html?testName=imaging-desaturate) 18:47:37 INFO - 2015-11-18 18:47:37,570 DEBUG : BROWSER_OUTPUT: RSS: Main: 158429184 18:47:37 INFO - 2015-11-18 18:47:37,570 DEBUG : BROWSER_OUTPUT: 18:47:40 INFO - 2015-11-18 18:47:40,312 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35307/tests/kraken/driver.html?testName=json-parse-financial) 18:47:40 INFO - 2015-11-18 18:47:40,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 156721152 18:47:40 INFO - 2015-11-18 18:47:40,880 DEBUG : BROWSER_OUTPUT: 18:47:42 INFO - 2015-11-18 18:47:42,435 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35307/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:47:42 INFO - 2015-11-18 18:47:42,996 DEBUG : BROWSER_OUTPUT: RSS: Main: 167276544 18:47:42 INFO - 2015-11-18 18:47:42,996 DEBUG : BROWSER_OUTPUT: 18:47:44 INFO - 2015-11-18 18:47:44,779 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:47:45 INFO - 2015-11-18 18:47:45,374 DEBUG : BROWSER_OUTPUT: RSS: Main: 166936576 18:47:45 INFO - 2015-11-18 18:47:45,374 DEBUG : BROWSER_OUTPUT: 18:47:47 INFO - 2015-11-18 18:47:47,428 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:47:47 INFO - 2015-11-18 18:47:47,995 DEBUG : BROWSER_OUTPUT: RSS: Main: 175312896 18:47:47 INFO - 2015-11-18 18:47:47,995 DEBUG : BROWSER_OUTPUT: 18:47:50 INFO - 2015-11-18 18:47:50,395 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:47:50 INFO - 2015-11-18 18:47:50,975 DEBUG : BROWSER_OUTPUT: RSS: Main: 181624832 18:47:50 INFO - 2015-11-18 18:47:50,976 DEBUG : BROWSER_OUTPUT: 18:47:53 INFO - 2015-11-18 18:47:53,530 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:47:54 INFO - 2015-11-18 18:47:54,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 180617216 18:47:54 INFO - 2015-11-18 18:47:54,096 DEBUG : BROWSER_OUTPUT: 18:47:55 INFO - 2015-11-18 18:47:55,765 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35307/tests/kraken/driver.html?testName=ai-astar) 18:47:56 INFO - 2015-11-18 18:47:56,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 239669248 18:47:56 INFO - 2015-11-18 18:47:56,016 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,016 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:47:56 INFO - 2015-11-18 18:47:56,016 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:47:56 INFO - 2015-11-18 18:47:56,016 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:47:56 INFO - 2015-11-18 18:47:56,017 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:47:56 INFO - 2015-11-18 18:47:56,017 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;105;111;110;111;119;110;110;109;111 18:47:56 INFO - 2015-11-18 18:47:56,017 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;123;158;170;122;154;154;125;168;124 18:47:56 INFO - 2015-11-18 18:47:56,017 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;160;162;160;160;163;160;160;160 18:47:56 INFO - 2015-11-18 18:47:56,018 DEBUG : BROWSER_OUTPUT: |3;audio-fft;74;71;82;91;72;71;71;110;93;72 18:47:56 INFO - 2015-11-18 18:47:56,018 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;131;90;90;90;144;91;92;136 18:47:56 INFO - 2015-11-18 18:47:56,018 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;109;109;109;109;108;110 18:47:56 INFO - 2015-11-18 18:47:56,019 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;144;149;145;145;145;145;145 18:47:56 INFO - 2015-11-18 18:47:56,020 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;96;95;96;95;95;96;95 18:47:56 INFO - 2015-11-18 18:47:56,020 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;57;57;57;58;58;59;65;57;56 18:47:56 INFO - 2015-11-18 18:47:56,020 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;69;69;69;69;69;68;68;69 18:47:56 INFO - 2015-11-18 18:47:56,020 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;84;84;84;84;90;84;85;85 18:47:56 INFO - 2015-11-18 18:47:56,020 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;120;117;111;127;121;114;116;120;124 18:47:56 INFO - 2015-11-18 18:47:56,021 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;153;153;151;150;141;149;149;143;140 18:47:56 INFO - 2015-11-18 18:47:56,021 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;68;69;65;66;67;67;65;68;66 18:47:56 INFO - 2015-11-18 18:47:56,021 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:47:56 INFO - 2015-11-18 18:47:56,021 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:47:56 INFO - 2015-11-18 18:47:56,021 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,910 18:47:56 INFO - 2015-11-18 18:47:56,022 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:47:56 INFO - 2015-11-18 18:47:56,022 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901276015__endTimestamp 18:47:56 INFO - 2015-11-18 18:47:56,022 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,023 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:47:56 INFO - 2015-11-18 18:47:56,024 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:47:56 INFO - 2015-11-18 18:47:56,024 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,024 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:3.46 (3.1%) stddev-sans-first:3.64 18:47:56 INFO - 2015-11-18 18:47:56,024 DEBUG : BROWSER_OUTPUT: Values: 112.0 105.0 111.0 110.0 111.0 119.0 110.0 110.0 109.0 111.0 18:47:56 INFO - 2015-11-18 18:47:56,025 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,025 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:145.50 Median:155.50 stddev:19.67 (12.6%) stddev-sans-first:20.41 18:47:56 INFO - 2015-11-18 18:47:56,025 DEBUG : BROWSER_OUTPUT: Values: 157.0 123.0 158.0 170.0 122.0 154.0 154.0 125.0 168.0 124.0 18:47:56 INFO - 2015-11-18 18:47:56,025 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,025 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:1.06 (0.7%) stddev-sans-first:1.12 18:47:56 INFO - 2015-11-18 18:47:56,025 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 160.0 162.0 160.0 160.0 163.0 160.0 160.0 160.0 18:47:56 INFO - 2015-11-18 18:47:56,025 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:73.00 stddev:13.32 (18.2%) stddev-sans-first:13.90 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: Values: 74.0 71.0 82.0 91.0 72.0 71.0 71.0 110.0 93.0 72.0 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.40 Median:90.00 stddev:22.72 (25.2%) stddev-sans-first:23.49 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 131.0 90.0 90.0 90.0 144.0 91.0 92.0 136.0 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 108.0 110.0 18:47:56 INFO - 2015-11-18 18:47:56,026 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,027 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:1.34 (0.9%) stddev-sans-first:1.41 18:47:56 INFO - 2015-11-18 18:47:56,027 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 144.0 149.0 145.0 145.0 145.0 145.0 145.0 18:47:56 INFO - 2015-11-18 18:47:56,027 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,028 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:47:56 INFO - 2015-11-18 18:47:56,028 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 18:47:56 INFO - 2015-11-18 18:47:56,028 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,028 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.00 stddev:2.53 (4.4%) stddev-sans-first:2.68 18:47:56 INFO - 2015-11-18 18:47:56,028 DEBUG : BROWSER_OUTPUT: Values: 58.0 57.0 57.0 57.0 58.0 58.0 59.0 65.0 57.0 56.0 18:47:56 INFO - 2015-11-18 18:47:56,028 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:69.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 69.0 69.0 69.0 69.0 69.0 68.0 68.0 69.0 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:1.95 (2.3%) stddev-sans-first:1.96 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 84.0 84.0 84.0 84.0 90.0 84.0 85.0 85.0 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.10 Median:120.50 stddev:4.72 (3.9%) stddev-sans-first:4.96 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: Values: 121.0 120.0 117.0 111.0 127.0 121.0 114.0 116.0 120.0 124.0 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.90 Median:149.50 stddev:5.32 (3.6%) stddev-sans-first:5.02 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: Values: 140.0 153.0 153.0 151.0 150.0 141.0 149.0 149.0 143.0 140.0 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.34 (2.0%) stddev-sans-first:1.39 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: Values: 66.0 68.0 69.0 65.0 66.0 67.0 67.0 65.0 68.0 66.0 18:47:56 INFO - 2015-11-18 18:47:56,029 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:47:56 INFO - 2015-11-18 18:47:56,030 DEBUG : BROWSER_OUTPUT: 18:47:56 INFO - 2015-11-18 18:47:56,865 INFO : Browser exited with error code: 0 18:47:56 INFO - 2015-11-18 18:47:56,916 INFO : Completed test kraken (00:00:58) 18:47:56 INFO - 2015-11-18 18:47:56,916 INFO : Starting test v8_7 18:47:56 INFO - 2015-11-18 18:47:56,916 DEBUG : operating with platform_type : linux_ 18:47:56 INFO - 2015-11-18 18:47:56,917 INFO : Initialising browser for v8_7 test... 18:47:56 INFO - 2015-11-18 18:47:56,923 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpf94J3C/profile http://localhost:35307/getInfo.html 18:48:00 INFO - 2015-11-18 18:48:00,882 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:48:00 INFO - 2015-11-18 18:48:00,882 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:48:00 INFO - 2015-11-18 18:48:00,882 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:48:00 INFO - 2015-11-18 18:48:00,882 DEBUG : BROWSER_OUTPUT: __metrics 18:48:01 INFO - 2015-11-18 18:48:01,059 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:48:02 INFO - 2015-11-18 18:48:02,292 INFO : Browser initialized. 18:48:02 INFO - 2015-11-18 18:48:02,293 INFO : Running cycle 1/1 for v8_7 test... 18:48:02 INFO - 2015-11-18 18:48:02,293 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpf94J3C/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:48:14 INFO - 2015-11-18 18:48:14,053 DEBUG : BROWSER_OUTPUT: RSS: Main: 139845632 18:48:14 INFO - 2015-11-18 18:48:14,053 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,488 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35307/tests/v8_7/run.html (next: http://localhost:35307/tests/v8_7/run.html) 18:48:35 INFO - 2015-11-18 18:48:35,738 DEBUG : BROWSER_OUTPUT: RSS: Main: 621252608 18:48:35 INFO - 2015-11-18 18:48:35,738 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,738 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:48:35 INFO - 2015-11-18 18:48:35,738 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |0;Richards;168.23687752355318 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;189.2147587511826 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.61081385525205 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5081.218274111675 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1127.3957158962796 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |5;Earley;798.0845969672786 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |6;Boyer;11720.93023255814 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |7;RegExp;25974.358974358973 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |8;Splay;625 18:48:35 INFO - 2015-11-18 18:48:35,739 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: __startTimestamp1447901315736__endTimestamp 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.24 Median:168.24 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: Values: 168.2 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:189.21 Median:189.21 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,740 DEBUG : BROWSER_OUTPUT: Values: 189.2 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.61 Median:281.61 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: Values: 281.6 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5081.22 Median:5081.22 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: Values: 5081.2 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1127.40 Median:1127.40 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: Values: 1127.4 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:798.08 Median:798.08 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: Values: 798.1 18:48:35 INFO - 2015-11-18 18:48:35,741 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11720.93 Median:11720.93 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: Values: 11720.9 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25974.36 Median:25974.36 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: Values: 25974.4 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:625.00 Median:625.00 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: Values: 625.0 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: Values: 4835.7 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:48:35 INFO - 2015-11-18 18:48:35,742 DEBUG : BROWSER_OUTPUT: 18:48:36 INFO - 2015-11-18 18:48:36,601 INFO : Browser exited with error code: 0 18:48:36 INFO - 2015-11-18 18:48:36,614 INFO : Completed test v8_7 (00:00:39) 18:48:36 INFO - 2015-11-18 18:48:36,713 INFO : Completed test suite (00:20:11) 18:48:36 INFO - 2015-11-18 18:48:36,713 DEBUG : Working with test: dromaeo_css 18:48:36 INFO - 2015-11-18 18:48:36,713 DEBUG : Generating results file: dromaeo_css 18:48:36 INFO - 2015-11-18 18:48:36,714 DEBUG : Working with test: kraken 18:48:36 INFO - 2015-11-18 18:48:36,714 DEBUG : Generating results file: kraken 18:48:36 INFO - 2015-11-18 18:48:36,714 INFO : javascript benchmark 18:48:36 INFO - 2015-11-18 18:48:36,715 DEBUG : Working with test: v8_7 18:48:36 INFO - 2015-11-18 18:48:36,715 DEBUG : Generating results file: v8_7 18:48:36 INFO - 2015-11-18 18:48:36,715 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:48:36 INFO - 2015-11-18 18:48:36,769 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 18:48:36 INFO - 2015-11-18 18:48:36,769 DEBUG : process_Request line: dromaeo_css 6698.41 graph.html#tests=[[72,64,35]] 18:48:36 INFO - 2015-11-18 18:48:36,769 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:48:36 INFO - 2015-11-18 18:48:36,799 DEBUG : process_Request line: kraken 1496.30 graph.html#tests=[[232,64,35]] 18:48:36 INFO - 2015-11-18 18:48:36,799 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:48:36 INFO - 2015-11-18 18:48:36,832 DEBUG : process_Request line: v8_7 20979.03 graph.html#tests=[[230,64,35]] 18:48:36 INFO - 2015-11-18 18:48:36,834 INFO : javascript benchmark 18:48:36 INFO - 2015-11-18 18:48:36,837 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3196.4885618301078, 3233.3371658809947, 3271.656476070014, 3287.9835600821993, 3288.3094550144474, 5137.6403651744395, 5241.449647786984, 5347.786088556458, 5353.116735738603, 5367.355917289926, 5328.626996110264, 5378.193271009351, 5451.863703407417, 5454.181872719094, 5472.781088756445, 4792.113459010076, 4858.246971719389, 4894.241392584155, 4972.129877271463, 4988.42633097193, 4939.160342741725, 4989.725565093919, 5004.399472063357, 5006.899862002758, 5032.446430892599, 4773.140834649762, 4801.279808028824, 4878.707277563322, 4883.4628190898975, 4919.458859525449, 4822.927656085161, 4842.636802239811, 4902.460729319772, 4918.458969513351, 4935.703857768545, 4881.682690625098, 4902.460729319772, 4907.362042934431, 4909.214525675875, 4922.360093187863, 5036.974815125937, 5080.79676812927, 5105.770240339157, 5192.402873669501, 5265.947340526623, 5761.3950535194035, 5772.163036359746, 5781.653100813965, 5783.913241301384, 5800.9129863052085, 5817.7963771267805, 5819.592628516006, 5823.388544202882, 5823.5341172706085, 5835.620684655484, 5229.816956406546, 5263.3947096083675, 5334.946650533522, 5378.91931621026, 5433.293671822677, 5764.74058667361, 5777.306723193244, 5802.33273173582, 5810.680412577264, 5838.4453476919625, 5616.943830561724, 5619.157126430986, 5630.211770352154, 5631.267935168484, 5632.746526406239, 5537.307836520435, 5625.831225063254, 5663.575231857545, 5713.400092990176, 5870.530357571384, 5702.429757024264, 5714.114312281603, 5714.485696287271, 5721.485066344049, 5732.455416735404, 5701.515371193412, 5705.229793977843, 5716.485516303553, 5717.342505611825, 5720.027595308808, 4322.027543802619, 4398.23030969579, 4398.934015989763, 4485.259932111165, 4486.035502367007, 3966.742161759534, 3985.103351745834, 3987.2932413188646, 3993.5008123984503, 4014.437978682987, 5382.434844341287, 5408.810691625853, 5414.729263536808, 5419.783208671688, 5441.156620723794, 5675.574331925122, 5733.627314224562, 5806.82579522615, 5807.12893066035, 5884.9705751470965, 5401.270828438111, 5401.513863752281, 5425.674459532441, 5426.267453893753, 5427.104527752956, 5040.067587496252, 5050.04049230645, 5051.1413059779925, 5054.620903432257, 5110.846674599694, 5757.0, 5776.653400795965, 5847.590668653153, 5881.0, 5896.351401345849, 5771.0, 5787.884242315215, 5796.507296879644, 5805.593608447368, 5820.417958204146, 5698.430156984434, 5983.252093488314, 5995.340512543529, 6017.939820601738, 6025.548083893813, 5604.551635869039, 5610.7194640268635, 5690.402507736793, 5690.687012214368, 5735.627184233178, 5785.4214578541805, 5799.507041901317, 5804.767809287581, 5806.390329015561, 5810.854728631818, 5426.755795989111, 5563.221149039056, 5638.633488823213, 5654.434556544313, 5656.37779844225, 5494.835154945358, 5568.136938774496, 5579.804706835242, 5580.107182850725, 5625.0, 5559.33288005442, 5593.8321850344555, 5596.440355964371, 5597.468240517144, 5607.747651355618, 5436.45635436469, 5537.418571049981, 5584.218209450762, 5714.628549144292, 5744.195812586325, 5424.294841670558, 5439.945600543944, 5440.156775699773, 5447.754851031792, 5448.972755136199], "unit": "ms", "name": "dojo.html", "value": 5297.5213913209145}, {"lowerIsBetter": false, "replicates": [555.017618814698, 602.0367412140573, 602.5360472436223, 604.8578584032749, 607.1257389359322, 30736.231594210156, 39400.60599394009, 39652.80173599135, 39675.80162099193, 39752.602473975254, 41515.0, 41817.16365672685, 41994.79002604983, 42044.789776051075, 42097.57902420975, 7522.887156692654, 7661.770146895602, 8668.696595619138, 8841.469511829278, 8849.646014159425, 10397.0, 10657.147428205764, 10665.0, 10666.893331066707, 10669.146468282557, 10331.173506119492, 10331.948340258288, 10397.480125993708, 10402.63590774323, 10408.0, 7706.113796913371, 7877.0, 7888.2506161914525, 7890.644920978512, 10248.538815753273, 7553.320201181865, 10304.433256170907, 10609.15126789855, 10618.309809862412, 10626.36241825493, 39067.60932390668, 39569.20861582781, 39593.20813583741, 39609.0, 39641.60358396408, 3405.9659403405894, 3445.810480423576, 3543.7696549724187, 3548.6451354864566, 3748.9437658435145, 29563.408731825242, 29712.257193570116, 30125.0, 30428.239294017825, 30546.08361749151, 3491.6159222485508, 3520.77114987525, 3537.663921927413, 3570.0896271450874, 3622.4385220290897, 274420.0, 274647.0, 275055.0, 275896.6205168981, 276318.0, 54134.72932635312, 55114.448855511735, 55138.448615514135, 56925.0, 57675.71162144246, 57426.7128664354, 57522.0, 57830.13254801181, 57843.42156578464, 57881.71059144761, 14473.493427729978, 14478.855211447752, 14500.927495362666, 14550.27248637564, 14610.26948652563, 11854.585089521825, 11933.462994165284, 11947.940260298643, 11964.880351196376, 12089.697757556132, 18880.055997200085, 18908.43274701761, 18942.337018204296, 19011.61976760457, 19054.714179287323, 7318.414526837841, 7533.623318834037, 7603.163651998276, 7664.156942736295, 7729.884051739228, 935.5930170375908, 956.4978386347224, 960.3338208208752, 984.3503287829998, 1019.9439030853374, 28642.570361444596, 28838.855805721258, 28975.85512072426, 29244.268893277836, 29350.0, 1054.4569546683465, 1061.5488417422564, 1104.5195340027115, 1109.14595761265, 1123.5168877382641, 14870.479533216285, 15428.151451670266, 15471.458498952483, 15478.380864765284, 15746.9212653936, 325064.0, 325882.0, 326773.0, 327320.0, 329119.0, 52365.2145217822, 52749.7362513185, 52967.0, 53014.73492632512, 53027.20459193115, 1336.064754671753, 1336.432016393029, 1338.2572672167041, 1351.148776270943, 1364.9454021839015, 28220.576691349237, 28575.285617858888, 28788.56590402808, 29276.41447171002, 29323.7067629321, 45543.0, 45957.310640339754, 46140.7692961533, 46238.537614624765, 46329.305060423445, 303418.0, 304709.0, 306274.0, 308277.0, 308419.0, 28142.18578142251, 28478.145655630364, 31350.216244594067, 32205.0, 32218.677813222504, 40523.0, 40604.0, 41629.0, 41768.791156044026, 41854.16291674088, 33795.66204337925, 34765.0, 34983.0, 35037.299254015285, 35325.82337088298, 28029.719702803526, 30951.380972380877, 34660.133496662784, 34842.65157348394, 35887.82056089807, 31825.0, 32039.0, 32464.51303230406, 32626.51060234146, 33352.49971250482, 30448.391032179676, 31615.36769264556, 31904.6809531911, 31972.36055278928, 32381.838090809397, 23603.29190124299, 23830.285091447284, 23835.284941451784, 23984.28047158588, 24020.39949001289], "unit": "ms", "name": "ext.html", "value": 18443.68675692306}, {"lowerIsBetter": false, "replicates": [354.91487510873634, 358.7829363235241, 359.71762166699136, 360.8304097074376, 362.91290090378317, 14251.50119745809, 14297.142171469693, 16387.344506219753, 17184.398546050892, 18143.54641133974, 11758.941205293962, 12037.638870833867, 16181.19094045296, 16548.66902661946, 16663.41678041269, 4561.452625684922, 4670.462896766864, 4675.11172877154, 4676.649251306166, 4678.625709943213, 3614.8734794282204, 3716.5319698719127, 4636.327732478789, 4664.603508701747, 5004.699718016931, 5028.673136246132, 5066.797328106872, 5083.923741143886, 5084.567811736007, 5086.033653605821, 4937.580305674004, 5053.595712343041, 5054.898902021976, 5055.014272216919, 5064.063148317573, 4382.868513944587, 4382.978085109586, 4383.60547550722, 4384.605385515287, 4385.736855788631, 13502.324883755773, 13742.793858092136, 13743.725125497534, 13753.79369309461, 13940.512082077079, 2070.7471979452516, 2076.8234700050516, 2097.2869224463757, 2126.0432805237706, 2127.8936053197276, 10123.392596444237, 10133.543990520444, 10165.440900750456, 10280.331778434453, 10290.7427314317, 1732.2378153612497, 1744.7470116832988, 1757.3409971260778, 1786.4908501077261, 1796.8987080437541, 58198.0, 59092.704536477475, 59726.701366493326, 60141.6992915037, 60441.6977915112, 6884.586924784529, 7040.295970403021, 7072.8939065914055, 7086.149662040588, 7127.89308160378, 8542.530160841088, 8548.48709077445, 8557.144285571392, 8562.443441176303, 8567.52878591671, 5602.187682785973, 5780.046292361797, 5820.039693450533, 5829.154772557956, 5835.883282334415, 5918.053111502225, 5949.6430214187285, 5977.043673012297, 5988.730507127189, 6011.579189456696, 3242.092214179986, 3285.5400243965864, 3304.801711897294, 3318.5022246663193, 3321.667833216659, 1126.532489017065, 1155.3536210899604, 1157.8147496400536, 1170.7716995185856, 1210.285931300537, 472.16191260512863, 481.2396413666399, 492.45583630088237, 495.4401526275358, 498.6559274100859, 11885.346305953082, 12081.237449549648, 12136.33250171249, 12197.51209951592, 12198.75602487963, 471.4507598647537, 497.9402471703419, 511.06220086141604, 513.4249640402772, 521.817363922624, 6224.0, 6288.842778930563, 6588.769393071219, 6643.435307998874, 6670.299073736166, 522108.0, 524976.0, 526077.0, 527294.0, 531071.0, 71762.0, 71997.0, 73798.63100684462, 74685.0, 75417.62291188509, 566.1705601294124, 572.6106247751486, 572.7909313100688, 573.3778849947853, 575.3010092737344, 10504.579816807242, 10908.34549927007, 11199.832002519806, 11253.7749245013, 11255.099592032782, 31817.840910795298, 31848.840755796075, 31958.36083278368, 32250.677493225707, 32849.178770530925, 39746.80126599348, 39851.0, 39874.0, 40537.0, 40603.79698101491, 6768.932310676831, 7001.544899581511, 7034.788956331318, 7045.365917067488, 7050.048243487268, 12318.938405307916, 12644.747105057664, 12653.87346126527, 12729.745405092031, 12839.688856006009, 10600.363978161335, 10851.0, 11043.116550676088, 11064.28082174656, 11204.0, 10737.946310268399, 10829.971152740478, 10995.34027958325, 11019.173561983043, 11038.0, 10988.725281868017, 11262.267952583055, 11262.267952583055, 11372.715682108013, 11374.886251137383, 6830.351116643911, 6889.311068893271, 6981.476389270723, 6983.301669832976, 6987.755428559976, 10687.30532515384, 10788.514516846604, 10884.891151088388, 11024.338539687644, 11153.888461115284], "unit": "ms", "name": "jquery.html", "value": 6993.890448118483}, {"lowerIsBetter": false, "replicates": [1317.965397163227, 1657.6767530331583, 1695.3049249807582, 1711.1925964728478, 1722.7932648082233, 6658.833529161768, 7062.929370706292, 7299.63501824908, 7321.743738969137, 7328.596927169004, 7148.96425517873, 7331.0, 7347.853042939151, 7354.48518603698, 7362.8527429451515, 1902.8858268503866, 1934.2843148035247, 2181.4873504726415, 2204.6582779669097, 2257.9096836126537, 2242.394553470562, 2246.213825161197, 2249.201533455616, 2250.639897616382, 2251.223327951854, 2219.533897881447, 2314.6528020796845, 2314.687517185184, 2316.096722278313, 2320.895559699809, 1996.7004949257582, 2048.2523878784286, 2245.315178870436, 2281.8402711810013, 2284.2119468783244, 2266.4447210433386, 2276.6243569811127, 2299.7930186283147, 2305.4697419593585, 2339.9181028664084, 7450.478466507347, 7481.8503629927645, 7518.28576285252, 7519.6240187990925, 7526.285002924714, 4600.194965880991, 4665.0, 4675.0, 4675.205215113405, 4706.482286948433, 3120.0, 3126.31221131353, 3160.4153231652217, 3161.96838031619, 3254.0400581828417, 1019.6686077024997, 1039.7868436970402, 1053.0995998421392, 1082.1018554599664, 1091.3997301484153, 9949.701508954742, 10026.799464010679, 10029.79940401188, 10042.397456152654, 10060.647877324258, 4197.391378250137, 4253.97873010633, 4284.635805956466, 4357.9128417431475, 4447.466304043471, 8394.916050839414, 8521.403501754941, 8555.786105347415, 8580.914190858011, 8581.485110893242, 4734.100520901018, 4742.288656701522, 4762.642801789811, 4765.070811191785, 4788.401449818773, 4364.170807546556, 4373.059792144641, 4402.537733537996, 4402.713823601455, 4411.228035093845, 3499.16020155166, 3514.367413865478, 3522.9647703522637, 3530.0998245447245, 3548.27260411615, 1068.9625863094755, 1072.2012100984816, 1073.4042606353394, 1080.475969154959, 1095.1731442760665, 1045.0176833776227, 1316.7366526694507, 1319.2018828608745, 1321.9074664773373, 1353.587155917435, 6339.778107766206, 7044.0, 7218.711251549879, 7221.277872212737, 7229.349358557755, 701.7017767448916, 709.4324540367685, 724.1925253342486, 726.785598248518, 730.171255624859, 5643.209950606918, 5663.716814159276, 5718.857028574319, 5737.770489180386, 5903.144044113579, 9033.683821066232, 9196.540172991323, 9227.35408521397, 9237.030111838294, 9266.768330791785, 8783.0, 8895.110488951183, 9130.589123489326, 9145.725628231165, 9157.084291570924, 7014.0, 7093.751718689821, 7105.573665580082, 7163.570185788765, 7219.675114619749, 6925.515213935178, 6957.686904089227, 7050.153981522045, 7072.0, 7100.751473698396, 7776.988991431304, 8034.799130021563, 8104.392170587112, 8142.51144931306, 8147.551884646401, 8031.357491400557, 8166.959165204136, 8176.67293308261, 8180.631871565913, 8185.590720464072, 6809.319068093151, 6903.0, 6907.6891539881835, 6911.205211400753, 6921.1002569665625, 7328.303811137934, 7402.148752893486, 7427.25727427253, 7430.108386993379, 7440.181580026085, 6942.097527321416, 7004.054452649031, 7048.471364647569, 7055.541389809646, 7055.929440705732, 6676.49926255523, 6765.25582185969, 6851.794446166623, 6956.304369563003, 6976.7558135465015, 6172.259328880384, 6905.620190889549, 6957.860842783217, 6987.895181572178, 7033.542819716702, 5725.742341594555, 6222.28443728977, 6366.745330186741, 6367.649779262185, 6369.9681501592195, 6407.583507072025, 6462.741490340334, 6465.741370345134, 6470.417662410406, 6474.384933431316], "unit": "ms", "name": "mootools.html", "value": 4359.37823036135}, {"lowerIsBetter": false, "replicates": [337.57634169117756, 342.4879804691986, 343.5448031358451, 344.42824910648324, 354.81904228843297, 5935.169076329317, 6025.9096113558335, 6099.146119543262, 6106.053561697939, 6154.199954005984, 5858.970705146468, 6008.789692360769, 6048.879022419549, 6050.60671056381, 6054.122152287927, 4669.369635099269, 4674.485806561275, 4684.953150468486, 4685.83599574015, 4686.742229177394, 4446.066326071529, 4512.954870451286, 4580.335851301559, 4652.348671186037, 4675.438947326325, 4609.170349337118, 4618.0, 4622.07558488302, 4640.559146881041, 4649.0, 4636.721796692209, 4641.187792136379, 4643.094596553673, 4643.558861908147, 4662.72023678577, 4095.1195492968855, 4104.56902025286, 4107.240160570274, 4107.876763697094, 4119.217348703737, 5608.551315894719, 5707.1724599933195, 5712.542996560265, 5718.399568045337, 5733.569982251306, 5424.701641409721, 5430.864228394283, 5431.21247419122, 5433.1850222466555, 5437.13005919055, 6152.938470615281, 6375.394337537927, 6387.616742995435, 6397.8400539986405, 6402.711877965503, 4435.911281774379, 4471.39636149119, 4496.235639941186, 4503.594676479132, 4506.985928166169, 6521.15225020749, 6562.114114594564, 6592.439642630334, 6593.86812263752, 6599.0, 4659.627229821606, 4665.346851440801, 4668.183067963093, 4670.9766451167525, 4672.415948006499, 4652.13935421948, 4673.252279635242, 4714.717116972993, 4715.622750179978, 4729.905401891943, 5395.0, 5395.190721391823, 5396.487333703292, 5402.783888644411, 5403.83788486346, 6495.415412612888, 6495.8700825983215, 6496.707648155844, 6498.480121590262, 6500.284968653445, 1663.8668906487694, 1692.2976964559816, 1692.3907393338388, 1695.474402935094, 1695.7625932369479, 1080.8054550180887, 1092.8633920759905, 1098.1544210957518, 1154.6362895687998, 1172.1209093180114, 6002.639841609517, 6129.509639228941, 6129.724162412613, 6130.570860039755, 6142.846428839315, 6615.66921653925, 6629.5359324846795, 6636.402723754885, 6637.402633762985, 6638.0, 5972.761089556369, 6131.877362452726, 6135.478484328793, 6135.5398345124295, 6142.262928448526, 3574.8212589370946, 3610.5667319921777, 3611.6749492545796, 3612.223371975038, 3612.349777040158, 6843.794686159423, 6850.725970961105, 6852.383285504229, 6856.897146542806, 6861.691223894937, 6647.734090636417, 6771.966140169267, 6788.049673045777, 6791.524593278522, 6799.354061364163, 6846.349596788297, 6848.554843935129, 6853.348931851466, 6862.759803406957, 6862.965685171542, 6469.417752402306, 6577.276499585138, 6608.669566521751, 6617.106690596708, 6625.4699624029, 6665.466762658879, 6668.83327916806, 6673.364021211497, 6676.83307917306, 6683.8997415039785, 6951.965240173767, 6970.093887794555, 6973.930260697531, 6981.79054628362, 6990.930090699231, 4482.282834746425, 4574.176648203355, 4578.4963653997365, 4580.496145423934, 4584.197765390976, 6708.463322934056, 6708.496862735216, 6712.966435167793, 6716.731330746716, 6718.7312507499155, 6508.414242718178, 6518.73925042993, 6522.608643481406, 6525.184351956005, 6529.281779004211, 6423.678816059271, 6537.836554086186, 6549.934500654933, 6550.901736473861, 6557.868842623026, 6419.390157934989, 6430.967845160744, 6437.549371543947, 6439.3238709934785, 6439.645819479973, 6588.341165883373, 6589.571677840925, 6590.505712071518, 6592.868142637025, 6595.307492713388, 6282.371762823681, 6421.7110230038825, 6422.550421470452, 6430.067640192053, 6435.935640643534], "unit": "ms", "name": "prototype.html", "value": 4881.091395629761}, {"lowerIsBetter": false, "replicates": [745.612281613561, 762.2529920677738, 831.1688311688312, 833.683200383854, 873.4715497124236, 14039.929800350998, 14199.50301739438, 14215.289235538221, 14772.636378179493, 15124.773128403056, 14669.339879705412, 14753.0, 14766.704665906702, 14772.335244913978, 14807.407703691839, 675.3246753246754, 808.923152300532, 820.7578764264556, 820.9055958564752, 821.0352835418389, 664.2195420381033, 811.2171754257141, 819.6885183630212, 820.327331588098, 820.7496713502379, 819.9139090395482, 851.6040041380757, 853.6116067189414, 855.5636625321068, 855.9186877246651, 694.8853439182528, 700.1947760075902, 834.2491757418311, 840.861257892447, 849.0617867256685, 607.9361667024987, 611.461913516103, 618.279704144671, 618.9319174890759, 619.6437048697018, 13031.739365212643, 13132.0, 13144.934275328562, 13156.342182890818, 13170.86829131706, 10070.395776253376, 10158.695239142837, 10178.287519873611, 10596.523156457977, 10662.360258384522, 9767.413955162641, 10079.5464204111, 10289.279750417474, 10292.433916134609, 10376.16990640747, 3309.652486488932, 3317.701406873393, 3320.9833950830334, 3334.3998080345527, 3361.8991430257133, 40098.19803603941, 47562.7621861892, 47668.28497572539, 47682.523174768154, 47796.522034779555, 7811.609419529114, 7850.136484986647, 7932.0, 8031.317338026216, 8058.274755272054, 12204.877951220551, 12216.816747748613, 12218.633440996784, 12258.019358451302, 12273.324967126893, 6880.965595171992, 6955.89566156508, 7064.116985376828, 7126.928730712731, 7251.093613298338, 4284.785760712014, 4314.913701726011, 4332.805023773938, 4370.2352088384405, 4375.168717943644, 3084.953725694116, 3117.8596963136715, 3150.5431712401573, 3153.684631536874, 3158.4156930967843, 498.99002019959397, 505.4591587001873, 511.5217271850757, 513.7816428018077, 513.7944822071202, 577.8670905691714, 607.6718571971174, 624.4286477872696, 670.4368330602317, 679.3919442099392, 9193.218576421079, 9231.815363692689, 9280.628774849065, 9488.905110948803, 9755.951220243995, 570.9494530064701, 625.7528276330823, 662.1888186970922, 695.1415002471944, 707.3845754193782, 4115.259253334369, 4363.672724545671, 4367.344898265285, 4410.562762843071, 4424.535423780521, 60191.699041504515, 60715.69642151849, 60722.39277607256, 60725.0, 60736.69631651814, 51147.0, 51628.483715162365, 51737.741311293204, 51761.22358164631, 52819.735901320244, 46126.769366152956, 47309.52690473051, 47827.52172478231, 48421.0, 48922.04311827487, 8781.341399394943, 8834.381593288408, 9192.724218273463, 9217.493037882981, 9305.627774888928, 36605.63394366022, 37728.62271377251, 37993.62006379901, 38195.42706859344, 38353.4246986301, 43940.78029609832, 45039.774801125786, 47260.7636961813, 47845.28232076591, 47889.0, 8344.791380215544, 8401.453905496122, 8475.982882054193, 8645.394822362374, 8662.956685216534, 12397.0, 12406.3201820564, 12586.748265034465, 12694.428750706056, 12831.0, 11459.140564457532, 11843.11176661771, 11908.875727825785, 12231.633051008128, 12553.0, 11585.0, 12060.75878482443, 12447.439865206268, 12452.128351015343, 12460.252384856938, 11674.474648641002, 11905.821412678644, 12205.816912746139, 12411.131220814455, 12468.064895133082, 10096.747581310527, 10455.68632941013, 10824.945875270574, 11041.944790275997, 11048.723781905517, 9512.762180945532, 9941.353812002197, 9961.015593762553, 10069.89930100719, 10274.486275686335], "unit": "ms", "name": "yui.html", "value": 6211.707945014321}], "name": "dromaeo_css", "value": 6698.4062887162045}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 105.0, 111.0, 110.0, 111.0, 119.0, 110.0, 110.0, 109.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 110.8}, {"lowerIsBetter": true, "replicates": [157.0, 123.0, 158.0, 170.0, 122.0, 154.0, 154.0, 125.0, 168.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 145.5}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 162.0, 160.0, 160.0, 163.0, 160.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 82.0, 91.0, 72.0, 71.0, 71.0, 110.0, 93.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 131.0, 90.0, 90.0, 90.0, 144.0, 91.0, 92.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 104.4}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 108.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 144.0, 149.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 57.0, 57.0, 58.0, 58.0, 59.0, 65.0, 57.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 58.2}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 69.0, 69.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.8}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 84.0, 84.0, 84.0, 84.0, 90.0, 84.0, 85.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.7}, {"lowerIsBetter": true, "replicates": [121.0, 120.0, 117.0, 111.0, 127.0, 121.0, 114.0, 116.0, 120.0, 124.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.1}, {"lowerIsBetter": true, "replicates": [140.0, 153.0, 153.0, 151.0, 150.0, 141.0, 149.0, 149.0, 143.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.9}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 69.0, 65.0, 66.0, 67.0, 67.0, 65.0, 68.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1496.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.23687752355318], "unit": "score", "name": "Richards", "value": 209.83508800000004}, {"lowerIsBetter": false, "replicates": [189.2147587511826], "unit": "score", "name": "DeltaBlue", "value": 349.43363}, {"lowerIsBetter": false, "replicates": [281.61081385525205], "unit": "score", "name": "Encrypt", "value": 945.2087309999996}, {"lowerIsBetter": false, "replicates": [5081.218274111675], "unit": "score", "name": "Decrypt", "value": 52.38527172827171}, {"lowerIsBetter": false, "replicates": [1127.3957158962796], "unit": "score", "name": "RayTrace", "value": 656.370243}, {"lowerIsBetter": false, "replicates": [798.0845969672786], "unit": "score", "name": "Earley", "value": 835.078139}, {"lowerIsBetter": false, "replicates": [11720.93023255814], "unit": "score", "name": "Boyer", "value": 56.86093055555553}, {"lowerIsBetter": false, "replicates": [25974.358974358973], "unit": "score", "name": "RegExp", "value": 35.072472852912156}, {"lowerIsBetter": false, "replicates": [625.0], "unit": "score", "name": "Splay", "value": 130.3856}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 20979.029608961828}]} 18:48:36 INFO - RETURN: dromaeo_css: 6698.41 18:48:36 INFO - RETURN: kraken: 1496.30 18:48:36 INFO - RETURN: v8_7: 20979.03 18:48:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "20979.03"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1496.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6698.41"}}} 18:48:36 INFO - Return code: 0 18:48:36 INFO - # TBPL SUCCESS # 18:48:36 INFO - Running post-action listener: _resource_record_post_action 18:48:36 INFO - Running post-run listener: _resource_record_post_run 18:48:37 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 8192; Write bytes: 460115968; Read time: 28; Write time: 664804 18:48:37 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 121303040; Read time: 0; Write time: 377544 18:48:38 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 250773504; Read time: 16; Write time: 228196 18:48:38 INFO - Running post-run listener: _upload_blobber_files 18:48:38 INFO - Blob upload gear active. 18:48:38 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:48:38 INFO - Copying logs to upload dir... 18:48:38 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1261.173510 ========= master_lag: 1.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2015-11-18 18:48:39.028062) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:39.036398) ========= 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=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010100 build_url: 'https://queue.taskcluster.net/v1/task/U5xH5cKVQXCNYRO7PYExIg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:39.062370) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:39.062720) ========= 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=01deea79d00cc2c334834894000001d2-1447899117.749684-1413546007 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:48:39.113293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 18:48:39.113632) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 18:48:39.113947) ========= ========= Total master_lag: 1.29 =========