builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0092 starttime: 1467873173.23 results: success (0) buildid: 20160706212122 builduid: 68a26aaddb1640da99369ab1290ad97e revision: e178424c2e2a702ff2c7738e639c59bbed5a17cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.230170) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.230632) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.230921) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.255781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.256091) ========= 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/. (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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vKG06Ka3ab/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-06 23:32:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-07-06 23:32:53 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100097 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.377729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.378051) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vKG06Ka3ab/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020592 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.426526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:53.426889) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e178424c2e2a702ff2c7738e639c59bbed5a17cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e178424c2e2a702ff2c7738e639c59bbed5a17cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vKG06Ka3ab/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-06 23:32:53,502 truncating revision to first 12 chars 2016-07-06 23:32:53,502 Setting DEBUG logging. 2016-07-06 23:32:53,502 attempt 1/10 2016-07-06 23:32:53,502 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e178424c2e2a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 23:32:54,123 unpacking tar archive at: fx-team-e178424c2e2a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.891947 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:54.340502) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:54.340848) ========= script_repo_revision: e178424c2e2a702ff2c7738e639c59bbed5a17cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:54.341255) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:54.341526) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 23:32:54.360707) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-07-06 23:32:54.361033) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (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', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vKG06Ka3ab/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 23:32:54 INFO - MultiFileLogger online at 20160706 23:32:54 in /builds/slave/test 23:32:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:32:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:32:54 INFO - {'append_to_log': False, 23:32:54 INFO - 'base_work_dir': '/builds/slave/test', 23:32:54 INFO - 'blob_upload_branch': 'Fx-Team', 23:32:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:32:54 INFO - 'branch': 'Fx-Team', 23:32:54 INFO - 'buildbot_json_path': 'buildprops.json', 23:32:54 INFO - 'config_files': ('talos/mac_config.py',), 23:32:54 INFO - 'default_actions': ('clobber', 23:32:54 INFO - 'read-buildbot-config', 23:32:54 INFO - 'download-and-extract', 23:32:54 INFO - 'populate-webroot', 23:32:54 INFO - 'create-virtualenv', 23:32:54 INFO - 'install', 23:32:54 INFO - 'run-tests'), 23:32:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:32:54 INFO - 'download_minidump_stackwalk': True, 23:32:54 INFO - 'download_symbols': 'ondemand', 23:32:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:32:54 INFO - 'tooltool.py': '/tools/tooltool.py', 23:32:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:32:54 INFO - '/tools/misc-python/virtualenv.py')}, 23:32:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:32:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:32:54 INFO - 'installer_path': 'installer.exe', 23:32:54 INFO - 'log_level': 'info', 23:32:54 INFO - 'log_name': 'talos', 23:32:54 INFO - 'log_to_console': True, 23:32:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:32:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:32:54 INFO - 'opt_config_files': (), 23:32:54 INFO - 'pip_index': False, 23:32:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:32:54 INFO - 'cmd': ('bash', 23:32:54 INFO - '-c', 23:32:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:32:54 INFO - 'enabled': True, 23:32:54 INFO - 'halt_on_failure': False, 23:32:54 INFO - 'name': 'check_screen_resolution'},), 23:32:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:32:54 INFO - 'cmd': ('bash', 23:32:54 INFO - '-c', 23:32:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:32:54 INFO - 'enabled': True, 23:32:54 INFO - 'halt_on_failure': False, 23:32:54 INFO - 'name': 'check_screen_resolution'},), 23:32:54 INFO - 'run_cmd_checks_enabled': True, 23:32:54 INFO - 'sps_profile': False, 23:32:54 INFO - 'sps_profile_interval': 0, 23:32:54 INFO - 'suite': 'dromaeojs', 23:32:54 INFO - 'system_bits': '32', 23:32:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:32:54 INFO - 'title': 't-yosemite-r7-0092', 23:32:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:32:54 INFO - 'use_talos_json': True, 23:32:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:32:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:32:54 INFO - 'work_dir': 'build'} 23:32:54 INFO - ##### 23:32:54 INFO - ##### Running clobber step. 23:32:54 INFO - ##### 23:32:54 INFO - Running pre-action listener: _resource_record_pre_action 23:32:54 INFO - Running main action method: clobber 23:32:54 INFO - rmtree: /builds/slave/test/build 23:32:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:32:56 INFO - Running post-action listener: _resource_record_post_action 23:32:56 INFO - ##### 23:32:56 INFO - ##### Finished clobber step (success) 23:32:56 INFO - ##### 23:32:56 INFO - ##### 23:32:56 INFO - ##### Running read-buildbot-config step. 23:32:56 INFO - ##### 23:32:56 INFO - Running pre-action listener: _resource_record_pre_action 23:32:56 INFO - Running main action method: read_buildbot_config 23:32:56 INFO - Using buildbot properties: 23:32:56 INFO - { 23:32:56 INFO - "project": "", 23:32:56 INFO - "product": "firefox", 23:32:56 INFO - "script_repo_revision": "production", 23:32:56 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 23:32:56 INFO - "repository": "", 23:32:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 23:32:56 INFO - "buildid": "20160706212122", 23:32:56 INFO - "pgo_build": "False", 23:32:56 INFO - "basedir": "/builds/slave/test", 23:32:56 INFO - "buildnumber": 90, 23:32:56 INFO - "slavename": "t-yosemite-r7-0092", 23:32:56 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:32:56 INFO - "platform": "yosemite_r7", 23:32:56 INFO - "scheduler": "tests-fx-team-macosx64-talos", 23:32:56 INFO - "branch": "fx-team", 23:32:56 INFO - "repo_path": "integration/fx-team", 23:32:56 INFO - "revision": "e178424c2e2a702ff2c7738e639c59bbed5a17cf", 23:32:56 INFO - "stage_platform": "macosx64", 23:32:56 INFO - "builduid": "68a26aaddb1640da99369ab1290ad97e", 23:32:56 INFO - "slavebuilddir": "test" 23:32:56 INFO - } 23:32:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 23:32:56 INFO - Running post-action listener: _resource_record_post_action 23:32:56 INFO - ##### 23:32:56 INFO - ##### Finished read-buildbot-config step (success) 23:32:56 INFO - ##### 23:32:56 INFO - ##### 23:32:56 INFO - ##### Running download-and-extract step. 23:32:56 INFO - ##### 23:32:56 INFO - Running pre-action listener: _resource_record_pre_action 23:32:56 INFO - Running main action method: download_and_extract 23:32:56 INFO - mkdir: /builds/slave/test/build/tests 23:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:32:56 INFO - https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:32:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:32:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:32:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:32:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 23:32:59 INFO - Downloaded 1199 bytes. 23:32:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:32:59 INFO - Using the following test package requirements: 23:32:59 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:32:59 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 23:32:59 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 23:32:59 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'jsshell-mac.zip'], 23:32:59 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 23:32:59 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:32:59 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 23:32:59 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 23:32:59 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 23:32:59 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:32:59 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 23:32:59 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:32:59 INFO - https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:32:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:32:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:32:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:32:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:33:01 INFO - Downloaded 18804840 bytes. 23:33:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:33:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:33:04 INFO - Return code: 0 23:33:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:04 INFO - https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:33:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:33:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:33:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:33:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:33:05 INFO - Downloaded 18804840 bytes. 23:33:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:33:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:33:09 INFO - Return code: 0 23:33:09 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')]} 23:33:09 INFO - https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:33:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:33:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:33:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:33:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:33:10 INFO - Downloaded 14403333 bytes. 23:33:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:33:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 23:33:10 INFO - Return code: 0 23:33: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')]} 23:33:10 INFO - https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:33:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:33:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:33:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 23:33:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 23:33:15 INFO - Downloaded 112389897 bytes. 23:33:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:33:15 INFO - mkdir: /builds/slave/test/properties 23:33:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:33:15 INFO - Writing to file /builds/slave/test/properties/build_url 23:33:15 INFO - Contents: 23:33:15 INFO - build_url:https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:33:16 INFO - Running post-action listener: _resource_record_post_action 23:33:16 INFO - Running post-action listener: set_extra_try_arguments 23:33:16 INFO - ##### 23:33:16 INFO - ##### Finished download-and-extract step (success) 23:33:16 INFO - ##### 23:33:16 INFO - ##### 23:33:16 INFO - ##### Running populate-webroot step. 23:33:16 INFO - ##### 23:33:16 INFO - Running pre-action listener: _resource_record_pre_action 23:33:16 INFO - Running main action method: populate_webroot 23:33:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:33:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:33:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:33:16 INFO - '--tppagecycles', 23:33:16 INFO - '7'], 23:33:16 INFO - 'tests': ['tsvgm']}}, 23:33:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:33:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:33:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:33:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:33:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:33:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:33:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['damp', 'tps']}, 23:33:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'tests': ['damp', 'tps']}, 23:33:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['dromaeo_dom']}, 23:33:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:33:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['basic_compositor_video']}, 23:33:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:33:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['a11yr', 23:33:16 INFO - 'ts_paint', 23:33:16 INFO - 'tpaint', 23:33:16 INFO - 'sessionrestore', 23:33:16 INFO - 'sessionrestore_no_auto_restore', 23:33:16 INFO - 'tabpaint']}, 23:33:16 INFO - 'other-e10s': {'tests': ['a11yr', 23:33:16 INFO - 'ts_paint', 23:33:16 INFO - 'tpaint', 23:33:16 INFO - 'sessionrestore', 23:33:16 INFO - 'sessionrestore_no_auto_restore', 23:33:16 INFO - 'tabpaint']}, 23:33:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:33:16 INFO - 'ts_paint', 23:33:16 INFO - 'tpaint', 23:33:16 INFO - 'sessionrestore', 23:33:16 INFO - 'sessionrestore_no_auto_restore', 23:33:16 INFO - 'tabpaint']}, 23:33:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:33:16 INFO - 'ts_paint', 23:33:16 INFO - 'tpaint', 23:33:16 INFO - 'sessionrestore', 23:33:16 INFO - 'sessionrestore_no_auto_restore', 23:33:16 INFO - 'tabpaint']}, 23:33:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['a11yr', 23:33:16 INFO - 'ts_paint', 23:33:16 INFO - 'tpaint', 23:33:16 INFO - 'sessionrestore', 23:33:16 INFO - 'sessionrestore_no_auto_restore', 23:33:16 INFO - 'tabpaint']}, 23:33:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['a11yr', 23:33:16 INFO - 'ts_paint', 23:33:16 INFO - 'tpaint', 23:33:16 INFO - 'sessionrestore', 23:33:16 INFO - 'sessionrestore_no_auto_restore', 23:33:16 INFO - 'tabpaint']}, 23:33:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['tsvgx', 23:33:16 INFO - 'tsvgr_opacity', 23:33:16 INFO - 'tart', 23:33:16 INFO - 'tscrollx', 23:33:16 INFO - 'cart']}, 23:33:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:33:16 INFO - 'tsvgr_opacity', 23:33:16 INFO - 'tart', 23:33:16 INFO - 'tscrollx', 23:33:16 INFO - 'cart']}, 23:33:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'talos_options': ['--disable-e10s'], 23:33:16 INFO - 'tests': ['tp5o']}, 23:33:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'tests': ['tp5o']}, 23:33:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'talos_options': ['--disable-e10s', 23:33:16 INFO - '--xperf_path', 23:33:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:33:16 INFO - 'tests': ['tp5n']}, 23:33:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:33:16 INFO - 'talos_options': ['--xperf_path', 23:33:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:33:16 INFO - 'tests': ['tp5n']}}, 23:33:16 INFO - 'talos.zip': {'path': '', 23:33:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:33:16 INFO - Running post-action listener: _resource_record_post_action 23:33:16 INFO - ##### 23:33:16 INFO - ##### Finished populate-webroot step (success) 23:33:16 INFO - ##### 23:33:16 INFO - ##### 23:33:16 INFO - ##### Running create-virtualenv step. 23:33:16 INFO - ##### 23:33:16 INFO - Running pre-action listener: _resource_record_pre_action 23:33:16 INFO - Running main action method: create_virtualenv 23:33:16 INFO - Creating virtualenv /builds/slave/test/build/venv 23:33:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:33:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:33:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:33:16 INFO - Using real prefix '/tools/python27' 23:33:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:33:17 INFO - Installing distribute.............................................................................................................................................................................................done. 23:33:20 INFO - Installing pip.................done. 23:33:20 INFO - Return code: 0 23:33:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:33:20 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')]} 23:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:20 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')]} 23:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:20 INFO - 'HOME': '/Users/cltbld', 23:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:20 INFO - 'LOGNAME': 'cltbld', 23:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:20 INFO - 'MOZ_NO_REMOTE': '1', 23:33:20 INFO - 'NO_EM_RESTART': '1', 23:33:20 INFO - 'PAGER': '/bin/cat', 23:33:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:20 INFO - 'PWD': '/builds/slave/test', 23:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:20 INFO - 'SHELL': '/bin/bash', 23:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:20 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:20 INFO - 'USER': 'cltbld', 23:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:20 INFO - 'XPC_FLAGS': '0x0', 23:33:20 INFO - 'XPC_SERVICE_NAME': '0', 23:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:20 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 23:33:20 INFO - Cleaning up... 23:33:20 INFO - Return code: 0 23:33:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:33:20 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')]} 23:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:20 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')]} 23:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:20 INFO - 'HOME': '/Users/cltbld', 23:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:20 INFO - 'LOGNAME': 'cltbld', 23:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:20 INFO - 'MOZ_NO_REMOTE': '1', 23:33:20 INFO - 'NO_EM_RESTART': '1', 23:33:20 INFO - 'PAGER': '/bin/cat', 23:33:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:20 INFO - 'PWD': '/builds/slave/test', 23:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:20 INFO - 'SHELL': '/bin/bash', 23:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:20 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:20 INFO - 'USER': 'cltbld', 23:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:20 INFO - 'XPC_FLAGS': '0x0', 23:33:20 INFO - 'XPC_SERVICE_NAME': '0', 23:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:20 INFO - Downloading/unpacking psutil>=3.1.1 23:33:20 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 23:33:20 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 23:33:20 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 23:33:20 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 23:33:20 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 23:33:20 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 23:33:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:33:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:33:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:33:23 INFO - Installing collected packages: psutil 23:33:23 INFO - Running setup.py install for psutil 23:33:23 INFO - building 'psutil._psutil_osx' extension 23:33:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:33:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:33:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:33:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 23:33:23 INFO - building 'psutil._psutil_posix' extension 23:33:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 23:33:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:33:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:33:24 INFO - ^ 23:33:24 INFO - 1 warning generated. 23:33:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 23:33:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:33:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:33:24 INFO - Successfully installed psutil 23:33:24 INFO - Cleaning up... 23:33:24 INFO - Return code: 0 23:33:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:33:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:24 INFO - 'HOME': '/Users/cltbld', 23:33:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:24 INFO - 'LOGNAME': 'cltbld', 23:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:24 INFO - 'MOZ_NO_REMOTE': '1', 23:33:24 INFO - 'NO_EM_RESTART': '1', 23:33:24 INFO - 'PAGER': '/bin/cat', 23:33:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:24 INFO - 'PWD': '/builds/slave/test', 23:33:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:24 INFO - 'SHELL': '/bin/bash', 23:33:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:24 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:24 INFO - 'USER': 'cltbld', 23:33:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:24 INFO - 'XPC_FLAGS': '0x0', 23:33:24 INFO - 'XPC_SERVICE_NAME': '0', 23:33:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:33:24 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 23:33:24 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 23:33:24 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 23:33:24 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 23:33:24 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 23:33:24 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 23:33:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:33:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:33:27 INFO - Installing collected packages: mozsystemmonitor 23:33:27 INFO - Running setup.py install for mozsystemmonitor 23:33:27 INFO - Successfully installed mozsystemmonitor 23:33:27 INFO - Cleaning up... 23:33:27 INFO - Return code: 0 23:33:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:33:27 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')]} 23:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:27 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')]} 23:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:27 INFO - 'HOME': '/Users/cltbld', 23:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:27 INFO - 'LOGNAME': 'cltbld', 23:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:27 INFO - 'MOZ_NO_REMOTE': '1', 23:33:27 INFO - 'NO_EM_RESTART': '1', 23:33:27 INFO - 'PAGER': '/bin/cat', 23:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:27 INFO - 'PWD': '/builds/slave/test', 23:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:27 INFO - 'SHELL': '/bin/bash', 23:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:27 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:27 INFO - 'USER': 'cltbld', 23:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:27 INFO - 'XPC_FLAGS': '0x0', 23:33:27 INFO - 'XPC_SERVICE_NAME': '0', 23:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:27 INFO - Downloading/unpacking jsonschema==2.5.1 23:33:27 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 23:33:27 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 23:33:27 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 23:33:27 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 23:33:27 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 23:33:27 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 23:33:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:33:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:33:29 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 23:33:29 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 23:33:29 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 23:33:29 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 23:33:29 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 23:33:29 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 23:33:30 INFO - Downloading functools32-3.2.3-2.tar.gz 23:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:33:30 INFO - warning: no files found matching '*.txt' 23:33:30 INFO - no previously-included directories found matching 'build' 23:33:30 INFO - no previously-included directories found matching 'dist' 23:33:30 INFO - no previously-included directories found matching '.git*' 23:33:30 INFO - Installing collected packages: jsonschema, functools32 23:33:30 INFO - Running setup.py install for functools32 23:33:30 INFO - warning: no files found matching '*.txt' 23:33:30 INFO - no previously-included directories found matching 'build' 23:33:30 INFO - no previously-included directories found matching 'dist' 23:33:30 INFO - no previously-included directories found matching '.git*' 23:33:30 INFO - Successfully installed jsonschema functools32 23:33:30 INFO - Cleaning up... 23:33:30 INFO - Return code: 0 23:33:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:33:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:30 INFO - 'HOME': '/Users/cltbld', 23:33:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:30 INFO - 'LOGNAME': 'cltbld', 23:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:30 INFO - 'MOZ_NO_REMOTE': '1', 23:33:30 INFO - 'NO_EM_RESTART': '1', 23:33:30 INFO - 'PAGER': '/bin/cat', 23:33:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:30 INFO - 'PWD': '/builds/slave/test', 23:33:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:30 INFO - 'SHELL': '/bin/bash', 23:33:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:30 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:30 INFO - 'USER': 'cltbld', 23:33:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:30 INFO - 'XPC_FLAGS': '0x0', 23:33:30 INFO - 'XPC_SERVICE_NAME': '0', 23:33:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:31 INFO - Downloading/unpacking blobuploader==1.2.4 23:33:31 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 23:33:31 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 23:33:31 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 23:33:31 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 23:33:31 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 23:33:31 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 23:33:33 INFO - Downloading blobuploader-1.2.4.tar.gz 23:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:33:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:33:33 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 23:33:33 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 23:33:33 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 23:33:33 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 23:33:33 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 23:33:33 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 23:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:33:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:33:34 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 23:33:34 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 23:33:34 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 23:33:34 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 23:33:34 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 23:33:34 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 23:33:34 INFO - Downloading docopt-0.6.1.tar.gz 23:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:33:34 INFO - Installing collected packages: blobuploader, requests, docopt 23:33:34 INFO - Running setup.py install for blobuploader 23:33:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:33:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:33:34 INFO - Running setup.py install for requests 23:33:35 INFO - Running setup.py install for docopt 23:33:35 INFO - Successfully installed blobuploader requests docopt 23:33:35 INFO - Cleaning up... 23:33:35 INFO - Return code: 0 23:33:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 23:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 23:33:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:35 INFO - 'HOME': '/Users/cltbld', 23:33:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:35 INFO - 'LOGNAME': 'cltbld', 23:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:35 INFO - 'MOZ_NO_REMOTE': '1', 23:33:35 INFO - 'NO_EM_RESTART': '1', 23:33:35 INFO - 'PAGER': '/bin/cat', 23:33:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:35 INFO - 'PWD': '/builds/slave/test', 23:33:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:35 INFO - 'SHELL': '/bin/bash', 23:33:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:35 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:35 INFO - 'USER': 'cltbld', 23:33:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:35 INFO - 'XPC_FLAGS': '0x0', 23:33:35 INFO - 'XPC_SERVICE_NAME': '0', 23:33:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:33:35 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-8uxFLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-cQR4Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-AHo30_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-fuBzZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-6TxCYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-9vOOx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-OGZDT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-FqRyin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-lBEKIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-4R_aVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:33:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:33:36 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-2Wbru7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:33:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Ty4uwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:33:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-iOVKyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:33:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-XuxjK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:33:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-kOGOV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:33:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-1a2PTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:33:37 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-15MVXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:33:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:33:37 INFO - Running setup.py install for manifestparser 23:33:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:33:37 INFO - Running setup.py install for mozcrash 23:33:37 INFO - Running setup.py install for mozdebug 23:33:38 INFO - Running setup.py install for mozdevice 23:33:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Running setup.py install for mozfile 23:33:38 INFO - Running setup.py install for mozhttpd 23:33:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Running setup.py install for mozinfo 23:33:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Running setup.py install for mozInstall 23:33:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:33:38 INFO - Running setup.py install for mozleak 23:33:38 INFO - Running setup.py install for mozlog 23:33:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:33:39 INFO - Running setup.py install for moznetwork 23:33:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:33:39 INFO - Running setup.py install for mozprocess 23:33:39 INFO - Running setup.py install for mozprofile 23:33:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:33:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:33:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:33:39 INFO - Running setup.py install for mozrunner 23:33:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:33:39 INFO - Running setup.py install for mozscreenshot 23:33:39 INFO - Running setup.py install for moztest 23:33:40 INFO - Running setup.py install for mozversion 23:33:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:33:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:33:40 INFO - Cleaning up... 23:33:40 INFO - Return code: 0 23:33:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:33:40 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')]} 23:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:40 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')]} 23:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 23:33:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 23:33:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:40 INFO - 'HOME': '/Users/cltbld', 23:33:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:40 INFO - 'LOGNAME': 'cltbld', 23:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:40 INFO - 'MOZ_NO_REMOTE': '1', 23:33:40 INFO - 'NO_EM_RESTART': '1', 23:33:40 INFO - 'PAGER': '/bin/cat', 23:33:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:40 INFO - 'PWD': '/builds/slave/test', 23:33:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:40 INFO - 'SHELL': '/bin/bash', 23:33:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:40 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:40 INFO - 'USER': 'cltbld', 23:33:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:40 INFO - 'XPC_FLAGS': '0x0', 23:33:40 INFO - 'XPC_SERVICE_NAME': '0', 23:33:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:33:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-PSjTyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:33:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-dsEhKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:33:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-JJKvkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:33:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-IgRMRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:33:40 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-IGmky4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Mx_MYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-fDckM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-KO3oqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-grkdXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-bucIhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-VGAhSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-EK7uV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-3Ae18F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:33:41 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-yWu0An-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:33:42 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-HyhA7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:33:42 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-oz_cq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:33:42 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-e9IRi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:33:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:33:42 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 23:33:42 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 23:33:42 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 23:33:42 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 23:33:42 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 23:33:42 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 23:33:44 INFO - Downloading blessings-1.6.tar.gz 23:33:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:33:44 INFO - Installing collected packages: blessings 23:33:44 INFO - Running setup.py install for blessings 23:33:45 INFO - Successfully installed blessings 23:33:45 INFO - Cleaning up... 23:33:45 INFO - Return code: 0 23:33:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:33:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:33:45 INFO - Reading from file tmpfile_stdout 23:33:45 INFO - Current package versions: 23:33:45 INFO - blessings == 1.6 23:33:45 INFO - blobuploader == 1.2.4 23:33:45 INFO - docopt == 0.6.1 23:33:45 INFO - functools32 == 3.2.3-2 23:33:45 INFO - jsonschema == 2.5.1 23:33:45 INFO - manifestparser == 1.1 23:33:45 INFO - mozInstall == 1.12 23:33:45 INFO - mozcrash == 0.17 23:33:45 INFO - mozdebug == 0.1 23:33:45 INFO - mozdevice == 0.48 23:33:45 INFO - mozfile == 1.2 23:33:45 INFO - mozhttpd == 0.7 23:33:45 INFO - mozinfo == 0.9 23:33:45 INFO - mozleak == 0.1 23:33:45 INFO - mozlog == 3.2 23:33:45 INFO - moznetwork == 0.27 23:33:45 INFO - mozprocess == 0.23 23:33:45 INFO - mozprofile == 0.28 23:33:45 INFO - mozrunner == 6.12 23:33:45 INFO - mozscreenshot == 0.1 23:33:45 INFO - mozsystemmonitor == 0.3 23:33:45 INFO - moztest == 0.7 23:33:45 INFO - mozversion == 1.4 23:33:45 INFO - psutil == 3.1.1 23:33:45 INFO - requests == 1.2.3 23:33:45 INFO - wsgiref == 0.1.2 23:33:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:33:45 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')]} 23:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:45 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')]} 23:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 23:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 23:33:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:45 INFO - 'HOME': '/Users/cltbld', 23:33:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:45 INFO - 'LOGNAME': 'cltbld', 23:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:45 INFO - 'MOZ_NO_REMOTE': '1', 23:33:45 INFO - 'NO_EM_RESTART': '1', 23:33:45 INFO - 'PAGER': '/bin/cat', 23:33:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:45 INFO - 'PWD': '/builds/slave/test', 23:33:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:45 INFO - 'SHELL': '/bin/bash', 23:33:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:45 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:45 INFO - 'USER': 'cltbld', 23:33:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:45 INFO - 'XPC_FLAGS': '0x0', 23:33:45 INFO - 'XPC_SERVICE_NAME': '0', 23:33:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:33:45 INFO - Cleaning up... 23:33:45 INFO - Return code: 0 23:33:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:33:45 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')]} 23:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:45 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')]} 23:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:33:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ede618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfba59f410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:33:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:33:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:33:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:33:45 INFO - 'HOME': '/Users/cltbld', 23:33:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:33:45 INFO - 'LOGNAME': 'cltbld', 23:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:33:45 INFO - 'MOZ_NO_REMOTE': '1', 23:33:45 INFO - 'NO_EM_RESTART': '1', 23:33:45 INFO - 'PAGER': '/bin/cat', 23:33:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:33:45 INFO - 'PWD': '/builds/slave/test', 23:33:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:33:45 INFO - 'SHELL': '/bin/bash', 23:33:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:33:45 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:33:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:33:45 INFO - 'USER': 'cltbld', 23:33:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:33:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:33:45 INFO - 'XPC_FLAGS': '0x0', 23:33:45 INFO - 'XPC_SERVICE_NAME': '0', 23:33:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:33:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:33:45 INFO - Cleaning up... 23:33:45 INFO - Return code: 0 23:33:45 INFO - Running post-action listener: _resource_record_post_action 23:33:45 INFO - Running post-action listener: _start_resource_monitoring 23:33:45 INFO - Starting resource monitoring. 23:33:45 INFO - ##### 23:33:45 INFO - ##### Finished create-virtualenv step (success) 23:33:45 INFO - ##### 23:33:45 INFO - ##### 23:33:45 INFO - ##### Running install step. 23:33:45 INFO - ##### 23:33:45 INFO - Running pre-action listener: _resource_record_pre_action 23:33:45 INFO - Running main action method: install 23:33:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:33:46 INFO - Reading from file tmpfile_stdout 23:33:46 INFO - Detecting whether we're running mozinstall >=1.0... 23:33:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:33:46 INFO - Reading from file tmpfile_stdout 23:33:46 INFO - Output received: 23:33:46 INFO - Usage: mozinstall [options] installer 23:33:46 INFO - Options: 23:33:46 INFO - -h, --help show this help message and exit 23:33:46 INFO - -d DEST, --destination=DEST 23:33:46 INFO - Directory to install application into. [default: 23:33:46 INFO - "/builds/slave/test"] 23:33:46 INFO - --app=APP Application being installed. [default: firefox] 23:33:46 INFO - mkdir: /builds/slave/test/build/application 23:33:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:34:21 INFO - Reading from file tmpfile_stdout 23:34:21 INFO - Output received: 23:34:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:34:21 INFO - Running post-action listener: _resource_record_post_action 23:34:21 INFO - ##### 23:34:21 INFO - ##### Finished install step (success) 23:34:21 INFO - ##### 23:34:21 INFO - ##### 23:34:21 INFO - ##### Running run-tests step. 23:34:21 INFO - ##### 23:34:21 INFO - Running pre-action listener: _resource_record_pre_action 23:34:21 INFO - Running main action method: run_tests 23:34:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:34:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:34:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:34:21 INFO - 2016-07-06 23:34:21.233 screenresolution[2177:20955] starting screenresolution argv=screenresolution get 23:34:21 INFO - 2016-07-06 23:34:21.236 screenresolution[2177:20955] Display 0: 1600x1200x32@60 23:34:21 INFO - 2016-07-06 23:34:21.242 screenresolution[2178:20957] starting screenresolution argv=screenresolution list 23:34:21 INFO - Available Modes on Display 0 23:34:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:34:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:34:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:34:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:34:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:34:21 INFO - Intel Iris: 23:34:21 INFO - Chipset Model: Intel Iris 23:34:21 INFO - Type: GPU 23:34:21 INFO - Bus: Built-In 23:34:21 INFO - VRAM (Dynamic, Max): 1536 MB 23:34:21 INFO - Vendor: Intel (0x8086) 23:34:21 INFO - Device ID: 0x0a2e 23:34:21 INFO - Revision ID: 0x0009 23:34:21 INFO - Displays: 23:34:21 INFO - Display: 23:34:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:34:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:34:21 INFO - Display Serial Number: 1600x1200 60 23:34:21 INFO - Main Display: Yes 23:34:21 INFO - Mirror: Off 23:34:21 INFO - Online: Yes 23:34:21 INFO - Rotation: Supported 23:34:21 INFO - Return code: 0 23:34:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:34:21 INFO - Python 2.7.3 23:34:21 INFO - Return code: 0 23:34:21 INFO - grabbing minidump binary from tooltool 23:34:21 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')]} 23:34:21 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/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101dadd30>, '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 0x101b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101da5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:34:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:34:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:34:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:34:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:34:21 INFO - Return code: 0 23:34:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:34:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:34:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:34:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:34:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:34:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0092 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:34:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vKG06Ka3ab/Render', 23:34:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:34:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:34:21 INFO - 'HOME': '/Users/cltbld', 23:34:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:34:21 INFO - 'LOGNAME': 'cltbld', 23:34:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:34:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:34:21 INFO - 'MOZ_NO_REMOTE': '1', 23:34:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:34:21 INFO - 'NO_EM_RESTART': '1', 23:34:21 INFO - 'PAGER': '/bin/cat', 23:34:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:34:21 INFO - 'PWD': '/builds/slave/test', 23:34:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:34:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:34:21 INFO - 'SHELL': '/bin/bash', 23:34:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners', 23:34:21 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 23:34:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:34:21 INFO - 'USER': 'cltbld', 23:34:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:34:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:34:21 INFO - 'XPC_FLAGS': '0x0', 23:34:21 INFO - 'XPC_SERVICE_NAME': '0', 23:34:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:34:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:34:21 INFO - mozversion application_buildid: 20160706212122 23:34:21 INFO - mozversion application_changeset: e178424c2e2a702ff2c7738e639c59bbed5a17cf 23:34:21 INFO - mozversion application_display_name: Nightly 23:34:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:34:21 INFO - mozversion application_name: Firefox 23:34:21 INFO - mozversion application_remotingname: firefox 23:34:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:34:21 INFO - mozversion application_vendor: Mozilla 23:34:21 INFO - mozversion application_version: 50.0a1 23:34:21 INFO - mozversion platform_buildid: 20160706212122 23:34:21 INFO - mozversion platform_changeset: e178424c2e2a702ff2c7738e639c59bbed5a17cf 23:34:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:34:21 INFO - mozversion platform_version: 50.0a1 23:34:21 INFO - using testdate: 1467873261 23:34:21 INFO - actual date: 1467873261 23:34:21 INFO - starting webserver on 'localhost:56329' 23:34:21 INFO - SUITE-START | Running 2 tests 23:34:21 INFO - TEST-START | dromaeo_css 23:34:21 INFO - operating with platform_type : mac_ 23:34:21 INFO - Initialising browser for dromaeo_css test... 23:34:21 INFO - TEST-INFO | started process 2188 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmp9AMvi7/profile http://localhost:56329/getInfo.html) 23:34:24 INFO - PROCESS | 2188 | __metrics Screen width/height:1600/1200 23:34:24 INFO - PROCESS | 2188 | colorDepth:24 23:34:24 INFO - PROCESS | 2188 | Browser inner width/height: 1024/689 23:34:24 INFO - PROCESS | 2188 | __metrics 23:34:24 INFO - PROCESS | 2188 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:34:24 INFO - PROCESS | 2188 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:34:24 INFO - TEST-INFO | 2188: exit 0 23:34:24 INFO - Browser initialized. 23:34:24 INFO - Running cycle 1/1 for dromaeo_css test... 23:34:24 INFO - TEST-INFO | started process 2190 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmp9AMvi7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:34:36 INFO - PROCESS | 2190 | RSS: Main: 243482624 23:34:36 INFO - PROCESS | 2190 | 23:35:27 INFO - PROCESS | 2190 | 1467873327991 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] 23:37:38 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:56329/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56329/tests/dromaeo/cssquery-ext.html) 23:37:40 INFO - PROCESS | 2190 | RSS: Main: 349175808 23:37:40 INFO - PROCESS | 2190 | 23:40:43 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:56329/tests/dromaeo/cssquery-ext.html (next: http://localhost:56329/tests/dromaeo/cssquery-jquery.html) 23:40:44 INFO - PROCESS | 2190 | RSS: Main: 382599168 23:40:44 INFO - PROCESS | 2190 | 23:43:46 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:56329/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56329/tests/dromaeo/cssquery-mootools.html) 23:43:46 INFO - PROCESS | 2190 | RSS: Main: 372744192 23:43:46 INFO - PROCESS | 2190 | 23:46:49 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:56329/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56329/tests/dromaeo/cssquery-prototype.html) 23:46:49 INFO - PROCESS | 2190 | RSS: Main: 370913280 23:46:49 INFO - PROCESS | 2190 | 23:49:53 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:56329/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56329/tests/dromaeo/cssquery-yui.html) 23:49:53 INFO - PROCESS | 2190 | RSS: Main: 341200896 23:49:53 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:56329/tests/dromaeo/cssquery-yui.html (next: http://localhost:56329/tests/dromaeo/cssquery-dojo.html) 23:52:56 INFO - PROCESS | 2190 | RSS: Main: 424415232 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | __start_tp_report 23:52:56 INFO - PROCESS | 2190 | _x_x_mozilla_page_load 23:52:56 INFO - PROCESS | 2190 | _x_x_mozilla_page_load_details 23:52:56 INFO - PROCESS | 2190 | |i|pagename|runs| 23:52:56 INFO - PROCESS | 2190 | |0;dojo.html;3339.3488269787385;3339.6994270515647;3339.916502087449;3380.9661903380957;3397.354502644497;5602.943970560294;5630.014747419201;5713.200151978716;5717.6283541569765;5773.191753154561;5719.19931209631;5800.680962547058;5802.274715660543;5845.766169353232;5941.197938278331;5149;5201.297824793661;5204.193350030751;5236.450172731866;5241.5020573045595;5171.482851714836;5287.86780330491;5289.656172348804;5318.388385335678;5327.840164795062;5249.973750131263;5263.947360526384;5293.15309550472;5301.363836339645;5341.43914888935;5076.974615126937;5140.742962851842;5158.071547121516;5183.844484665466;5201.9479805201845;5088.363954505687;5120.436751957282;5144.125498665236;5147.382314122292;5199;5647.548196144339;5728.97135514324;5794.420557944214;5808.680522571257;5828.6502809831545;6283;6288.591241569284;6288.811335659938;6297.559170858041;6298.4646305064125;6176.783812566584;6178.907316390258;6200.689965501707;6254.718537665816;6264.091706702548;5900.909628348997;5923.644581325091;5949;5961.791337303173;5965.164876917235;5315.574754019669;6224.595401298901;6293;6293.118963345135;6302.275238347557;6029.849253768691;6072.957203290619;6092.979221685204;6111.834843226386;6125.723854043132;6147.354527774609;6212.4408803207925;6216.689165541705;6237.563370564017;6277.403646653652;5715.768514868304;6178.814635560941;6253.499720022387;6258.43674069336;6271.749130034747;6188.752449901954;6231;6291.465225455888;6296.087067375205;6299.086632438362;4865.9026819464125;4920.852374428773;4924.236743304794;4964.975175124101;4976.328195693535;4328;4506.7070640408265;4526.343680166357;4537.251353526631;4541.955350269456;5699.800506982236;5712.685802280831;5757.798477053283;5762.078067509178;5781.046127388934;6228.201543368002;6278.811635650938;6284.654344011032;6296.5592408531875;6300.464460520907;5593.776248950077;5715.057015592381;5726.284214473191;5730.512906402919;5753.85615359611;5399.082156033483;5399.892002160013;5439.156930675752;5449.264349312792;5476.863078423071;6288.591241569375;6295.748170073238;6302;6304.65324407162;6321.715522801393;6135.263289243823;6258.217722784652;6294.842628934313;6297.2128483939505;6302.684865756785;6214.689265536615;6247.281562620356;6271.278802937721;6300.149479820349;6309.747610095545;6215.782447614311;6221.4089661482085;6224.284207316216;6227.439530442282;6227.844303892439;6032.969835150796;6211.068339749074;6277.905831412624;6304.495640348669;6310.6529140895855;6042.516598672008;6096.207493026056;6111.877762444815;6119.541034422347;6143.539234557336;6044.607100538451;6079.908801367895;6106.816795496142;6114.847128821815;6129.754809807558;5776.971115144397;5842.883142337045;5929.140274660236;5966.105084237399;6024.578279520391;6054.848628784315;6098.069176274567;6134.509239260938;6155.322914479493;6176.258848938156;5974.9103763444455;5983.820485385445;6000.429959153874;6006.459418652167;6052.515798736179 23:52:56 INFO - PROCESS | 2190 | |1;ext.html;502.25164504887715;528.7805560692312;532.7932233491146;533.5864704853738;552.5055075707245;40659.796701016494;41492.37761433577;41496.17007659845;41569;43461.7826910865;34800;36444.63555364445;41926;43007.99999999992;43126.353104703376;8823.470591764515;8997.730068097968;9846.655367062136;10011.349262297963;10024.14794742444;10027;12194.939025304906;12214.938925305361;12423.627291181234;12425.378731063456;12129.514819407257;12142.757144857098;12171.817422738623;12196.329201893892;12225.755484890342;8914.019457859662;9096.909030909672;9105.408148470395;9147.496887671176;12053.035757139452;11682.94158529202;12019.278843269345;12028.097892658086;12171;12194.939025304817;41053.384199237036;41223.587764122276;41229.58770412317;41304.38043429321;41445.79277103626;3541.274038822061;3551.9112022199392;3565.16218688608;3672.4307732301536;4205.768682722449;31795.72817087314;32192.356152877044;32287.515687264477;32528.186795330068;32881.6711832881;3293.8353082346025;3294.6540613235506;3295.24209431832;3366.074329559366;3384.4923261510726;539700;540409;541104;542767;544292;33683.49474757881;57660;57834.421655782906;58317.4168258312;58579.41420585739;33599.66400336014;35580.110497237256;35598.82200588981;35615.46576801298;35629.465558016134;14746.11523308605;14909.776353354708;14918.701625967637;14948.476803311833;14951.252437378087;11544.307341559534;11637.418129093512;12570.87429125697;12625.747485050248;12651.430685619169;6912.861742765217;7718.228177182237;7889.644965976545;7923.049234091945;7994.6002699864775;2857.8856845725936;3259.1363288728508;3305.6694330567234;3306.156929982839;3329.1510664780326;976.7899901521207;993.6472552243994;1066.8453074304182;1078.347599702169;1078.396098185019;28925.132246032652;30002.699973000428;30413.056296785002;31041.8447907759;32073.679263207538;930.6417029443713;1024.2778840917135;1037.9740506487399;1080.8378743188584;1101.8457415961773;13546.86453135456;15322.770158447633;15424.691506169815;15462.14958177311;15895.205239738198;714790;720120;723490;725444;726688;55885;56276;56390.71804640951;56419.43580564224;56426.43573564294;1285.6014635463036;1310.698539335958;1407.183833376639;1411.2872999135782;1457.5627311806415;29787.191627211563;30375.633096511152;30445;30784.53823192699;31244;48268.5173148264;48564.2715359277;49158.75420622874;49200;49268.52194434173;577670;579761;585909;586552;589446;22983.425414364774;25659.717014148846;25718.228453146436;25797.3550661235;25879;45397.09205815931;45519;46036.30945535753;46150.769246153555;46553.53446465492;29941.850290748407;37361;38950.80524597472;39135.41296880606;39425.2114957705;36958.63041369552;37739.43390849194;37990.251218597674;38080.619193808816;38704.41943370908;36251.63748362483;36304.63695363013;36622.8168859154;36895.44656830203;36922.63077369192;30634;32979.175520612174;33618.831905840314;33818.32363352768;33841.83079084589;24232.151874684303;24470.755292446847;24870.378240543407;25407.745922540536;25510.234692959242 23:52:56 INFO - PROCESS | 2190 | |2;jquery.html;364.8321771984887;386.88200098969816;389.95905429929854;391.7590681730737;416.5189206466532;15207.391704331827;17339.479815605522;20712.792872071273;20971.790282097176;21510.354689359305;19997.90011549364;20605.896970515165;20879.269225577107;21273.361799146012;21277.893610531926;5825.359210486843;5980.342162362137;6048.2137322148055;6050.485708714765;6058.666773327465;5787.565932555054;5819.62172458791;5942.257217847769;5951.553633477485;5955.9702201488935;5791.333996590404;5877.471027607515;5893.469587737103;5900.586958912856;5903.940960590404;5720.2849643794525;5881.470667639933;5894.233749612523;5899.970500147493;5903.468687818074;4417.933730994037;5028.396592408898;5161.458046905059;5165.22521621756;5194.71429071401;25660.35849103769;25674.87162564194;25980;25989.480210395875;26001.479970400676;1890.914908829106;2074.8962551872346;2228.5320082782473;2738.3291093682174;3108.098651391094;10144.442055686934;10185.949070254675;10218.591256349737;10243.026912443305;10355.378677279312;1804.3053424431557;1890.4706682129026;1930.1217945834612;1935.854810889189;1941.7572803399576;127346;129027.35486322602;132004;133019;138291.30854345864;16251.618612418077;19806.454192826033;20553.89723051375;20779.8961005197;20832.37502874916;34200.315993679986;35111.64888351084;35149;35333.76331828425;35385.82307088448;10949;10968.945155274172;11106.777864442665;11151.163662725328;11303.378314192798;9181.495017774087;9188.678396256098;9193;9330.393524420891;9355.204807591295;9448.433094014381;9532.094451027142;9639.277054220962;9677.322587418814;9682.951585242028;3611.5124458198334;3637.781733096023;3684.4393146763277;3755.845090124254;3900.932505771945;883.4566741453959;920.4983284110176;936.6768464879606;951.9000504946881;968.2496065549115;20006.799932000784;20567.691484627743;20850.58298834045;21126.577468450545;21200.469988250417;831.8918540589684;905.0496978172893;947.2232769129248;964.4454438697777;974.3666616699202;10895.945520272347;11023.645555511364;11098.944505277423;11126.109911207084;11162.330260184415;584678;592310;601172.9941350265;621296;626892;101536;107729.46135269273;117619;131542;133059.60159044192;978.6183388478499;980.6861804222581;1009.4498498318434;1011.4942528735633;1012.372329155928;15280.847191527942;18234.270629175215;18740.625187496447;18904.716429253847;19431.611367772282;52164.478355215964;54164.45835541594;55349.72325138348;56035.59911002094;56863.71568142133;67841;73437.63281183774;75659.24340756742;84089;89029;7691.000169977867;8053.35573154158;8162.42862999608;8185.386096042702;8263.00843898736;8816.162300249465;10558.841617375592;12604.180728252633;12614.306213158365;12954.546590869275;10946.616868409568;10953.233273670767;11043.061339785887;11285.21003529745;11431;10772.946135269274;11011.559537618727;11031.944840275748;11068.778624427627;11148.665540033811;5585.301837270341;11354.148438866952;11394.886051139383;11429.257098288585;11751;7938.166492518202;7974.920250797418;8046.597670116354;8068.314193293401;8183.3453323732765;10982.231243813176;10985.670429887115;11047.337159770439;11145.83281250798;11295.661130166109 23:52:56 INFO - PROCESS | 2190 | |3;mootools.html;1546.5360391882432;1587.3967892200963;1805.6298458815943;1931.2854243929748;1954.0620502158963;8817.823643527126;9050.547472626358;9212.861807072892;9242.353035287533;9288.907110928889;8029.397795165357;8276.462029968048;8690.391672582922;8954.32842536809;8994.280457563396;2415.1064106280655;2447.645091461737;2448.375664205634;2448.865312407817;2451.142100264911;2413.925803017658;2423.660687503751;2459.594166962458;2461.9753802462014;2462.052109937669;2141.635921893282;2217.846719705752;2353.8352315337934;2412.2039726890084;2418.4679370538543;2175.1516908405733;2407.350015495815;2421.9394515137265;2430.6354046892843;2434.513097380513;2015.6875684268816;2395.2574701842486;2397.4006498375406;2412.457197130649;2417.6736140620965;8946.597403116875;9033.774155646095;9041.457512549268;9050.5927233274;9058.275337972946;5339.786408543654;5510.9448905510835;5541.224228607998;5544.86137846553;5547.916781248285;3630.2591543384574;3691.151035261905;3749.906252343686;3760.623937606245;3849.8460061597784;1117.608836907076;1122;1129.0628778114146;1135.0125390909961;1143.1997601678859;10999.680038395363;11563.826542601952;12114.636560903187;12275.754484910252;12314.938425307817;4478.910421791546;4631.791569379386;4699.295105734099;4705.435347758223;4707.7881495332795;9478.38390504615;9481.241500680067;9484.762880928032;9485.383450075724;9526.714198574055;5379.515843574097;5386.192071189353;5387.111126664135;5387.4073851876265;5391.56867450603;4908.828191013297;5040.672356296829;5045.31888195089;5050.898982020412;5125.56432703217;3322.2192784695576;3847.3074846527893;3882.281777871103;3883.0292426893275;3923.6272554107313;1100.6973082402403;1126.718320419895;1170.6253998720329;1181.4860535667012;1184.3367714080143;710.9822254443577;788.984220315602;829.9211025666609;853.6201390381345;940.4639355567261;6110.602810817282;6255.937440625627;6302.684865756785;6311.431971122621;6327.462165715873;877.9385443018928;962.6245764151986;979.5298256836759;987.1707765477033;1015.5836107196014;4061.5532291447917;4124.546299907008;4132.938005929914;4188.162367526507;4213.641840443535;10735.194860385502;10987.945060274647;11165.888341116648;11184.105271578253;11203.047740942111;10123.1901447884;10674.57301707923;10975.231733778719;11088.445577721082;11100.44497775108;8295.294899933573;8310.8337833243;8520;8539.231469167804;8549.914500855037;6140.8771824564155;6160.907586386118;6176.722047507783;6186.1648677427975;6239.1577137085915;10498.632547860789;10521.05310522026;10525.684229473129;10534.42060686639;10544.367337959748;10016.348937319051;10337.17302615804;10613.469326533488;10747.247692661438;10777.676669699922;7702.345300649507;7759.72840950564;7849.058113026245;7855;7859.449838511478;7759.534427934342;7916.6437510311025;8457.196566326189;8457.83084338322;8558.486490810572;7541.5475071495885;7623.542587444771;7672.347850432552;7685.346745526469;7748.728794492165;6860.5197636164985;7149.785506434815;7506.549607023596;7507.924920750941;7634.122075961113;6092.786752463642;6975.476839236976;7088.929110708827;7162.140543134858;7163.212046674757;5079.466656001215;5098;5120.308758317579;5233.712145832016;5261.947380526146;5054.620903432331;5066.996066058521;5168.612354073384;5182.559482444034;5279.419263881046 23:52:56 INFO - PROCESS | 2190 | |4;prototype.html;308.9721925026748;313.3810723820455;317.4523946192818;320.2250553660141;320.7402004376457;4970.70175789452;5459.7816087356505;5486.670799752018;5501.239603326932;5543.944560554393;4323.610875021247;4773.06925149596;5103.719295438749;5466.200990311735;5479.4520547945185;3423.6747508986605;3918.686505079601;3948.539040554999;4067.5932406759234;4114.218298523286;3403.7957722536726;3463.8614455421753;4061.979690101545;4153.833846646131;4191.874243772691;3422.19578399076;4079.9192411869863;4083.3262511685534;4114.753114813106;4129.926219183019;3329.933401331984;3495.860165593386;3840.0783811885085;3932.5280966283945;4122.773247471388;2982.209714425679;3552.769070010441;3575.5173051637958;3580.910477238064;3626.184108575576;4368;5285.814996475105;5299.019681358961;5365.4098049214945;5384.1923711443205;4588.810776510327;4740.19416699162;4750.596199323062;4769.4515130760055;4773.689710168863;5594.888102237973;5617.4944255016835;5661.207430959669;5671.432856714337;5701.515371193454;3501.8074005929666;3987.1626958338784;4002.519697636273;4020.155767288873;4024.3762216856435;5418.403975562725;5672.4611161939965;5864.560157988168;6017.946859299605;6055.939440605582;3826.693864490834;3987.2623564640635;3990.660793832557;4011.5357894368344;4021.2158629067635;3467.7225821934185;4084.81618327176;4096.160287141129;4117.485314335708;4121.649659778893;3933.1347103637154;4357.4988876279;4371.038371558252;4754.667173297905;4757.35775670287;5345.599080069011;5528.977139229255;5718.799052199085;5790.739416726257;5809.862674155993;1322.9073964822562;1352.2832898563777;1356.379126943313;1369.3084992079137;1371.6626289367987;764.9464537482434;792.469045739356;814.9877751833727;821.843849668561;826.5330088499978;3962.8018599069933;4507.459104907367;4693.647976401783;5241.764120614582;5336.599755018389;4976.576990955737;5253.080710875582;5638.943610563924;5735.655860648374;5940;4693.06138772247;5071.467495912876;5289.18017707256;5318.627696061239;5363.678179309254;3026.379592183597;3037;3068.6831442261396;3082.9845850770603;3091.6753740857334;5462.7268636568015;5903.35063143054;5963.761449542057;5986;6163.2573504790835;5197.350331208599;5267.394249661262;5493.862653433616;5560.221568980346;5731.197632331477;5975.19334889793;5989.78378000134;6037.335893051761;6040.75836966534;6045.969770151209;5114.4374118847645;5126.7180305083575;5525.336959564878;5821.661017965806;5831.375880853548;5453.972730136324;5679.432056794452;5712.685802280748;5716.857078573069;5792.855178620568;5056.84829455117;5162.741862906914;5914.467697907209;6037.396260373927;6039.849003774941;3281.721053710366;3693.4829123922136;3819.9045023874623;3829.8659546915724;3876.4572959786215;4459.598636122764;5665.3004098771025;5789.768409263583;5798;5833.82498525045;5131.127708289674;5638.718064096696;5670.53331800668;5670.858228544319;5718.3423906249645;5283.365996080495;5596.852645207641;5626.212330273846;5721.223992165151;5778.768849245983;5343.812966546152;5475.342958844964;5501.807436739695;5504.256925315031;5542.556595472433;4110.712250142461;4957.909259962802;5071.18860982241;5543.9168412474655;5569.35952365467;4549.7042692224895;4927.304521608793;5620.662760234399;5647.802326918702;5675.347335056521 23:52:56 INFO - PROCESS | 2190 | |5;yui.html;762.5653377574782;789.1516619633895;842.7303262955849;852.812381276119;857.3624377439093;15210;15718.842811571882;15738.763918541232;15768.053916765002;16403.58991025225;14849.480268190615;15053.096814191156;15058.548243552683;15080.321385537649;15085.698286034272;710.7157137145132;728.9963550182242;736.1571001203619;910.8314961732068;914.2366124286195;782.3232903538427;865.9956700216491;878.9648414063462;879.9912000880005;880.3925291548843;954.8233576788316;954.89973552777;956.5312996631635;956.8373376526007;958.2334132693852;753.9660715267826;808.8827120067616;919.1314208073373;943.938643988142;961.1061712607273;705.1397295299727;706.5901776969346;706.9293070692889;708.6633848921772;710.474249055695;15013.699726005529;15081.54755357341;15082.24588770557;15095.622609434742;15107.697846043127;13837.654058648513;13971.79042314366;13972.510962116277;14082.647933801634;14140.575782726535;11212.27120237182;11599.478023488879;11949.581764638197;11971.401429928555;12657.620271391874;4690.413698287714;4711.528847115295;4740.525947405266;4881.609471242292;4934.851954441372;104008.47995759973;120654;121497;122720.38639806834;123534.76465235323;11243.046906400523;11476.88523114775;11526.538938442536;11697.532098716127;11843.822342664867;21654.566908661738;21760.782392176192;21796.673049904264;22149;22164.113435462725;7958.283754462126;8057.556834374166;8070.838583228303;8138.308243799225;8204.589770511451;4476.239039363316;4499.955000450019;4512.571305725951;4566.06395688883;4661.743604101739;3556.8043757593064;3666.871659491905;3670.743047986669;3675;3675.72432067596;562.3083607163212;570.8201916396322;571.665575638248;600.2376981233808;660.8050625065617;582.248898920388;583.9416058394212;633.6609913696151;655.2268323378364;657.4674513643964;9067.637294508279;9108.81782364349;9294.72115836526;9390.154886060287;9413.48225847585;630.4294613374904;646.8188907106017;657.763205246111;661.5732852310233;662.8276648071536;4367.694261401672;4437.911241775147;4463.196624607602;4499.4150760400935;4505.48186958504;139002.3049884744;148829.25585372004;151833;157737.2113139427;159086;82385.58807205925;110764;115544;116848;119697;109309.45345273222;116420.83579164269;118716;120037.99999999825;121124.3943780293;8711.433756805787;8731.607077681647;8788.121187881161;8825.514596697243;8925;55651.33046008626;56825.43174568201;65044.349556505724;65949.67025164844;66049;69201.61596767936;85311.57344213488;98705;99753;100668;11449.713757156138;11484.655460336204;11634.418279086181;11711.238769479956;11963.102767292661;12521.37393130358;12527.248365098123;12667;12678.302693351954;12962.157459765085;11969.461374238006;12002.21985570935;12015.098867584791;12425.13024088305;12446.626601201979;11583.247088939192;11963.282203067844;12116.818247726114;12240.265584064628;12488.875111248772;11731.88268117342;11919.165658403828;12025.819612705642;12057.336846473529;12299.323537205539;10012.849807253042;10032.147267482345;10072.143867771321;10326.741831453974;10345.275181723588;9013.459192448474;9192.334223075233;9341.626334946526;9379.202767764544;10099.798004040025 23:52:56 INFO - PROCESS | 2190 | __end_tp_report 23:52:56 INFO - PROCESS | 2190 | __start_cc_report 23:52:56 INFO - PROCESS | 2190 | _x_x_mozilla_cycle_collect,1252 23:52:56 INFO - PROCESS | 2190 | __end_cc_report 23:52:56 INFO - PROCESS | 2190 | __startTimestamp1467874376972__endTimestamp 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | ------- Summary: start ------- 23:52:56 INFO - PROCESS | 2190 | Number of tests: 6 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | [#0] dojo.html Cycles:165 Average:5758.20 Median:5983.82 stddev:635.55 (10.6%) stddev-sans-first:608.51 23:52:56 INFO - PROCESS | 2190 | Values: 3339.3 3339.7 3339.9 3381.0 3397.4 5602.9 5630.0 5713.2 5717.6 5773.2 5719.2 5800.7 5802.3 5845.8 5941.2 5149.0 5201.3 5204.2 5236.5 5241.5 5171.5 5287.9 5289.7 5318.4 5327.8 5250.0 5263.9 5293.2 5301.4 5341.4 5077.0 5140.7 5158.1 5183.8 5201.9 5088.4 5120.4 5144.1 5147.4 5199.0 5647.5 5729.0 5794.4 5808.7 5828.7 6283.0 6288.6 6288.8 6297.6 6298.5 6176.8 6178.9 6200.7 6254.7 6264.1 5900.9 5923.6 5949.0 5961.8 5965.2 5315.6 6224.6 6293.0 6293.1 6302.3 6029.8 6073.0 6093.0 6111.8 6125.7 6147.4 6212.4 6216.7 6237.6 6277.4 5715.8 6178.8 6253.5 6258.4 6271.7 6188.8 6231.0 6291.5 6296.1 6299.1 4865.9 4920.9 4924.2 4965.0 4976.3 4328.0 4506.7 4526.3 4537.3 4542.0 5699.8 5712.7 5757.8 5762.1 5781.0 6228.2 6278.8 6284.7 6296.6 6300.5 5593.8 5715.1 5726.3 5730.5 5753.9 5399.1 5399.9 5439.2 5449.3 5476.9 6288.6 6295.7 6302.0 6304.7 6321.7 6135.3 6258.2 6294.8 6297.2 6302.7 6214.7 6247.3 6271.3 6300.1 6309.7 6215.8 6221.4 6224.3 6227.4 6227.8 6033.0 6211.1 6277.9 6304.5 6310.7 6042.5 6096.2 6111.9 6119.5 6143.5 6044.6 6079.9 6106.8 6114.8 6129.8 5777.0 5842.9 5929.1 5966.1 6024.6 6054.8 6098.1 6134.5 6155.3 6176.3 5974.9 5983.8 6000.4 6006.5 6052.5 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | [#1] ext.html Cycles:180 Average:72702.57 Median:19566.01 stddev:166553.60 (851.2%) stddev-sans-first:166932.61 23:52:56 INFO - PROCESS | 2190 | Values: 502.3 528.8 532.8 533.6 552.5 40659.8 41492.4 41496.2 41569.0 43461.8 34800.0 36444.6 41926.0 43008.0 43126.4 8823.5 8997.7 9846.7 10011.3 10024.1 10027.0 12194.9 12214.9 12423.6 12425.4 12129.5 12142.8 12171.8 12196.3 12225.8 8914.0 9096.9 9105.4 9147.5 12053.0 11682.9 12019.3 12028.1 12171.0 12194.9 41053.4 41223.6 41229.6 41304.4 41445.8 3541.3 3551.9 3565.2 3672.4 4205.8 31795.7 32192.4 32287.5 32528.2 32881.7 3293.8 3294.7 3295.2 3366.1 3384.5 539700.0 540409.0 541104.0 542767.0 544292.0 33683.5 57660.0 57834.4 58317.4 58579.4 33599.7 35580.1 35598.8 35615.5 35629.5 14746.1 14909.8 14918.7 14948.5 14951.3 11544.3 11637.4 12570.9 12625.7 12651.4 6912.9 7718.2 7889.6 7923.0 7994.6 2857.9 3259.1 3305.7 3306.2 3329.2 976.8 993.6 1066.8 1078.3 1078.4 28925.1 30002.7 30413.1 31041.8 32073.7 930.6 1024.3 1038.0 1080.8 1101.8 13546.9 15322.8 15424.7 15462.1 15895.2 714790.0 720120.0 723490.0 725444.0 726688.0 55885.0 56276.0 56390.7 56419.4 56426.4 1285.6 1310.7 1407.2 1411.3 1457.6 29787.2 30375.6 30445.0 30784.5 31244.0 48268.5 48564.3 49158.8 49200.0 49268.5 577670.0 579761.0 585909.0 586552.0 589446.0 22983.4 25659.7 25718.2 25797.4 25879.0 45397.1 45519.0 46036.3 46150.8 46553.5 29941.9 37361.0 38950.8 39135.4 39425.2 36958.6 37739.4 37990.3 38080.6 38704.4 36251.6 36304.6 36622.8 36895.4 36922.6 30634.0 32979.2 33618.8 33818.3 33841.8 24232.2 24470.8 24870.4 25407.7 25510.2 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | [#2] jquery.html Cycles:180 Average:36390.80 Median:18654.21 stddev:100972.18 (541.3%) stddev-sans-first:101219.20 23:52:56 INFO - PROCESS | 2190 | Values: 364.8 386.9 390.0 391.8 416.5 15207.4 17339.5 20712.8 20971.8 21510.4 19997.9 20605.9 20879.3 21273.4 21277.9 5825.4 5980.3 6048.2 6050.5 6058.7 5787.6 5819.6 5942.3 5951.6 5956.0 5791.3 5877.5 5893.5 5900.6 5903.9 5720.3 5881.5 5894.2 5900.0 5903.5 4417.9 5028.4 5161.5 5165.2 5194.7 25660.4 25674.9 25980.0 25989.5 26001.5 1890.9 2074.9 2228.5 2738.3 3108.1 10144.4 10185.9 10218.6 10243.0 10355.4 1804.3 1890.5 1930.1 1935.9 1941.8 127346.0 129027.4 132004.0 133019.0 138291.3 16251.6 19806.5 20553.9 20779.9 20832.4 34200.3 35111.6 35149.0 35333.8 35385.8 10949.0 10968.9 11106.8 11151.2 11303.4 9181.5 9188.7 9193.0 9330.4 9355.2 9448.4 9532.1 9639.3 9677.3 9683.0 3611.5 3637.8 3684.4 3755.8 3900.9 883.5 920.5 936.7 951.9 968.2 20006.8 20567.7 20850.6 21126.6 21200.5 831.9 905.0 947.2 964.4 974.4 10895.9 11023.6 11098.9 11126.1 11162.3 584678.0 592310.0 601173.0 621296.0 626892.0 101536.0 107729.5 117619.0 131542.0 133059.6 978.6 980.7 1009.4 1011.5 1012.4 15280.8 18234.3 18740.6 18904.7 19431.6 52164.5 54164.5 55349.7 56035.6 56863.7 67841.0 73437.6 75659.2 84089.0 89029.0 7691.0 8053.4 8162.4 8185.4 8263.0 8816.2 10558.8 12604.2 12614.3 12954.5 10946.6 10953.2 11043.1 11285.2 11431.0 10772.9 11011.6 11031.9 11068.8 11148.7 5585.3 11354.1 11394.9 11429.3 11751.0 7938.2 7974.9 8046.6 8068.3 8183.3 10982.2 10985.7 11047.3 11145.8 11295.7 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | [#3] mootools.html Cycles:180 Average:5833.71 Median:5060.81 stddev:3268.14 (64.6%) stddev-sans-first:3261.43 23:52:56 INFO - PROCESS | 2190 | Values: 1546.5 1587.4 1805.6 1931.3 1954.1 8817.8 9050.5 9212.9 9242.4 9288.9 8029.4 8276.5 8690.4 8954.3 8994.3 2415.1 2447.6 2448.4 2448.9 2451.1 2413.9 2423.7 2459.6 2462.0 2462.1 2141.6 2217.8 2353.8 2412.2 2418.5 2175.2 2407.4 2421.9 2430.6 2434.5 2015.7 2395.3 2397.4 2412.5 2417.7 8946.6 9033.8 9041.5 9050.6 9058.3 5339.8 5510.9 5541.2 5544.9 5547.9 3630.3 3691.2 3749.9 3760.6 3849.8 1117.6 1122.0 1129.1 1135.0 1143.2 10999.7 11563.8 12114.6 12275.8 12314.9 4478.9 4631.8 4699.3 4705.4 4707.8 9478.4 9481.2 9484.8 9485.4 9526.7 5379.5 5386.2 5387.1 5387.4 5391.6 4908.8 5040.7 5045.3 5050.9 5125.6 3322.2 3847.3 3882.3 3883.0 3923.6 1100.7 1126.7 1170.6 1181.5 1184.3 711.0 789.0 829.9 853.6 940.5 6110.6 6255.9 6302.7 6311.4 6327.5 877.9 962.6 979.5 987.2 1015.6 4061.6 4124.5 4132.9 4188.2 4213.6 10735.2 10987.9 11165.9 11184.1 11203.0 10123.2 10674.6 10975.2 11088.4 11100.4 8295.3 8310.8 8520.0 8539.2 8549.9 6140.9 6160.9 6176.7 6186.2 6239.2 10498.6 10521.1 10525.7 10534.4 10544.4 10016.3 10337.2 10613.5 10747.2 10777.7 7702.3 7759.7 7849.1 7855.0 7859.4 7759.5 7916.6 8457.2 8457.8 8558.5 7541.5 7623.5 7672.3 7685.3 7748.7 6860.5 7149.8 7506.5 7507.9 7634.1 6092.8 6975.5 7088.9 7162.1 7163.2 5079.5 5098.0 5120.3 5233.7 5261.9 5054.6 5067.0 5168.6 5182.6 5279.4 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | [#4] prototype.html Cycles:180 Average:4552.16 Median:5146.93 stddev:1413.32 (27.5%) stddev-sans-first:1380.93 23:52:56 INFO - PROCESS | 2190 | Values: 309.0 313.4 317.5 320.2 320.7 4970.7 5459.8 5486.7 5501.2 5543.9 4323.6 4773.1 5103.7 5466.2 5479.5 3423.7 3918.7 3948.5 4067.6 4114.2 3403.8 3463.9 4062.0 4153.8 4191.9 3422.2 4079.9 4083.3 4114.8 4129.9 3329.9 3495.9 3840.1 3932.5 4122.8 2982.2 3552.8 3575.5 3580.9 3626.2 4368.0 5285.8 5299.0 5365.4 5384.2 4588.8 4740.2 4750.6 4769.5 4773.7 5594.9 5617.5 5661.2 5671.4 5701.5 3501.8 3987.2 4002.5 4020.2 4024.4 5418.4 5672.5 5864.6 6017.9 6055.9 3826.7 3987.3 3990.7 4011.5 4021.2 3467.7 4084.8 4096.2 4117.5 4121.6 3933.1 4357.5 4371.0 4754.7 4757.4 5345.6 5529.0 5718.8 5790.7 5809.9 1322.9 1352.3 1356.4 1369.3 1371.7 764.9 792.5 815.0 821.8 826.5 3962.8 4507.5 4693.6 5241.8 5336.6 4976.6 5253.1 5638.9 5735.7 5940.0 4693.1 5071.5 5289.2 5318.6 5363.7 3026.4 3037.0 3068.7 3083.0 3091.7 5462.7 5903.4 5963.8 5986.0 6163.3 5197.4 5267.4 5493.9 5560.2 5731.2 5975.2 5989.8 6037.3 6040.8 6046.0 5114.4 5126.7 5525.3 5821.7 5831.4 5454.0 5679.4 5712.7 5716.9 5792.9 5056.8 5162.7 5914.5 6037.4 6039.8 3281.7 3693.5 3819.9 3829.9 3876.5 4459.6 5665.3 5789.8 5798.0 5833.8 5131.1 5638.7 5670.5 5670.9 5718.3 5283.4 5596.9 5626.2 5721.2 5778.8 5343.8 5475.3 5501.8 5504.3 5542.6 4110.7 4957.9 5071.2 5543.9 5569.4 4549.7 4927.3 5620.7 5647.8 5675.3 23:52:56 INFO - PROCESS | 2190 | 23:52:56 INFO - PROCESS | 2190 | [#5] yui.html Cycles:180 Average:24566.56 Median:4021.71 stddev:39636.26 (985.6%) stddev-sans-first:39707.15 23:52:56 INFO - PROCESS | 2190 | Values: 762.6 789.2 842.7 852.8 857.4 15210.0 15718.8 15738.8 15768.1 16403.6 14849.5 15053.1 15058.5 15080.3 15085.7 710.7 729.0 736.2 910.8 914.2 782.3 866.0 879.0 880.0 880.4 954.8 954.9 956.5 956.8 958.2 754.0 808.9 919.1 943.9 961.1 705.1 706.6 706.9 708.7 710.5 15013.7 15081.5 15082.2 15095.6 15107.7 13837.7 13971.8 13972.5 14082.6 14140.6 11212.3 11599.5 11949.6 11971.4 12657.6 4690.4 4711.5 4740.5 4881.6 4934.9 104008.5 120654.0 121497.0 122720.4 123534.8 11243.0 11476.9 11526.5 11697.5 11843.8 21654.6 21760.8 21796.7 22149.0 22164.1 7958.3 8057.6 8070.8 8138.3 8204.6 4476.2 4500.0 4512.6 4566.1 4661.7 3556.8 3666.9 3670.7 3675.0 3675.7 562.3 570.8 571.7 600.2 660.8 582.2 583.9 633.7 655.2 657.5 9067.6 9108.8 9294.7 9390.2 9413.5 630.4 646.8 657.8 661.6 662.8 4367.7 4437.9 4463.2 4499.4 4505.5 139002.3 148829.3 151833.0 157737.2 159086.0 82385.6 110764.0 115544.0 116848.0 119697.0 109309.5 116420.8 118716.0 120038.0 121124.4 8711.4 8731.6 8788.1 8825.5 8925.0 55651.3 56825.4 65044.3 65949.7 66049.0 69201.6 85311.6 98705.0 99753.0 100668.0 11449.7 11484.7 11634.4 11711.2 11963.1 12521.4 12527.2 12667.0 12678.3 12962.2 11969.5 12002.2 12015.1 12425.1 12446.6 11583.2 11963.3 12116.8 12240.3 12488.9 11731.9 11919.2 12025.8 12057.3 12299.3 10012.8 10032.1 10072.1 10326.7 10345.3 9013.5 9192.3 9341.6 9379.2 10099.8 23:52:56 INFO - PROCESS | 2190 | -------- Summary: end -------- 23:52:56 INFO - PROCESS | 2190 | 23:52:57 INFO - TEST-INFO | 2190: exit 0 23:52:57 INFO - TEST-OK | dromaeo_css | took 1115774ms 23:52:57 INFO - TEST-START | kraken 23:52:57 INFO - operating with platform_type : mac_ 23:52:57 INFO - Initialising browser for kraken test... 23:52:57 INFO - TEST-INFO | started process 2192 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpCB4OSU/profile http://localhost:56329/getInfo.html) 23:52:59 INFO - PROCESS | 2192 | __metrics Screen width/height:1600/1200 23:52:59 INFO - PROCESS | 2192 | colorDepth:24 23:52:59 INFO - PROCESS | 2192 | Browser inner width/height: 1024/689 23:52:59 INFO - PROCESS | 2192 | __metrics 23:52:59 INFO - PROCESS | 2192 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:52:59 INFO - PROCESS | 2192 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:53:00 INFO - TEST-INFO | 2192: exit 0 23:53:00 INFO - Browser initialized. 23:53:00 INFO - Running cycle 1/1 for kraken test... 23:53:00 INFO - TEST-INFO | started process 2193 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpCB4OSU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:53:12 INFO - PROCESS | 2193 | RSS: Main: 243658752 23:53:12 INFO - PROCESS | 2193 | 23:53:15 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56329/tests/kraken/driver.html?testName=audio-beat-detection) 23:53:16 INFO - PROCESS | 2193 | RSS: Main: 294207488 23:53:16 INFO - PROCESS | 2193 | 23:53:18 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56329/tests/kraken/driver.html?testName=audio-dft) 23:53:19 INFO - PROCESS | 2193 | RSS: Main: 304152576 23:53:19 INFO - PROCESS | 2193 | 23:53:22 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56329/tests/kraken/driver.html?testName=audio-fft) 23:53:23 INFO - PROCESS | 2193 | RSS: Main: 389070848 23:53:23 INFO - PROCESS | 2193 | 23:53:25 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56329/tests/kraken/driver.html?testName=audio-oscillator) 23:53:26 INFO - PROCESS | 2193 | RSS: Main: 289898496 23:53:26 INFO - PROCESS | 2193 | 23:53:28 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56329/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:53:28 INFO - PROCESS | 2193 | RSS: Main: 294252544 23:53:28 INFO - PROCESS | 2193 | 23:53:31 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56329/tests/kraken/driver.html?testName=imaging-darkroom) 23:53:32 INFO - PROCESS | 2193 | RSS: Main: 328286208 23:53:32 INFO - PROCESS | 2193 | 23:53:34 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56329/tests/kraken/driver.html?testName=imaging-desaturate) 23:53:35 INFO - PROCESS | 2193 | RSS: Main: 333156352 23:53:35 INFO - PROCESS | 2193 | 23:53:38 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56329/tests/kraken/driver.html?testName=json-parse-financial) 23:53:38 INFO - PROCESS | 2193 | RSS: Main: 332468224 23:53:38 INFO - PROCESS | 2193 | 23:53:40 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56329/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:53:41 INFO - PROCESS | 2193 | RSS: Main: 311443456 23:53:41 INFO - PROCESS | 2193 | 23:53:42 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:53:43 INFO - PROCESS | 2193 | RSS: Main: 318648320 23:53:43 INFO - PROCESS | 2193 | 23:53:45 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:53:46 INFO - PROCESS | 2193 | RSS: Main: 315633664 23:53:46 INFO - PROCESS | 2193 | 23:53:49 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:53:50 INFO - PROCESS | 2193 | RSS: Main: 325005312 23:53:50 INFO - PROCESS | 2193 | 23:53:52 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:53:53 INFO - PROCESS | 2193 | RSS: Main: 326197248 23:53:53 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | Cycle 1(1): loaded http://localhost:56329/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56329/tests/kraken/driver.html?testName=ai-astar) 23:53:55 INFO - PROCESS | 2193 | RSS: Main: 370143232 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | __start_tp_report 23:53:55 INFO - PROCESS | 2193 | _x_x_mozilla_page_load 23:53:55 INFO - PROCESS | 2193 | _x_x_mozilla_page_load_details 23:53:55 INFO - PROCESS | 2193 | |i|pagename|runs| 23:53:55 INFO - PROCESS | 2193 | |0;ai-astar;101;113;112;112;112;112;113;112;112;111 23:53:55 INFO - PROCESS | 2193 | |1;audio-beat-detection;144;116;107;156;107;108;129;109;138;107 23:53:55 INFO - PROCESS | 2193 | |2;audio-dft;185;182;185;184;183;179;181;181;182;184 23:53:55 INFO - PROCESS | 2193 | |3;audio-fft;78;73;57;109;57;56;59;70;81;56 23:53:55 INFO - PROCESS | 2193 | |4;audio-oscillator;81;90;92;72;69;68;94;69;67;99 23:53:55 INFO - PROCESS | 2193 | |5;imaging-gaussian-blur;87;84;87;85;87;84;88;86;90;85 23:53:55 INFO - PROCESS | 2193 | |6;imaging-darkroom;100;92;93;93;92;93;93;93;93;92 23:53:55 INFO - PROCESS | 2193 | |7;imaging-desaturate;85;86;85;85;84;86;84;85;83;86 23:53:55 INFO - PROCESS | 2193 | |8;json-parse-financial;55;55;54;54;53;55;53;55;54;53 23:53:55 INFO - PROCESS | 2193 | |9;json-stringify-tinderbox;64;67;76;76;71;70;69;70;70;73 23:53:55 INFO - PROCESS | 2193 | |10;stanford-crypto-aes;85;83;81;81;81;84;82;93;100;80 23:53:55 INFO - PROCESS | 2193 | |11;stanford-crypto-ccm;141;143;140;142;143;145;136;137;181;139 23:53:55 INFO - PROCESS | 2193 | |12;stanford-crypto-pbkdf2;164;165;161;162;225;203;162;162;160;162 23:53:55 INFO - PROCESS | 2193 | |13;stanford-crypto-sha256-iterative;62;79;85;80;65;66;65;69;66;66 23:53:55 INFO - PROCESS | 2193 | __end_tp_report 23:53:55 INFO - PROCESS | 2193 | __start_cc_report 23:53:55 INFO - PROCESS | 2193 | _x_x_mozilla_cycle_collect,1984 23:53:55 INFO - PROCESS | 2193 | __end_cc_report 23:53:55 INFO - PROCESS | 2193 | __startTimestamp1467874435557__endTimestamp 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | ------- Summary: start ------- 23:53:55 INFO - PROCESS | 2193 | Number of tests: 14 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:3.56 (3.2%) stddev-sans-first:0.60 23:53:55 INFO - PROCESS | 2193 | Values: 101.0 113.0 112.0 112.0 112.0 112.0 113.0 112.0 112.0 111.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:122.50 stddev:18.32 (15.0%) stddev-sans-first:17.64 23:53:55 INFO - PROCESS | 2193 | Values: 144.0 116.0 107.0 156.0 107.0 108.0 129.0 109.0 138.0 107.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.50 stddev:1.96 (1.1%) stddev-sans-first:1.87 23:53:55 INFO - PROCESS | 2193 | Values: 185.0 182.0 185.0 184.0 183.0 179.0 181.0 181.0 182.0 184.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#3] audio-fft Cycles:10 Average:69.60 Median:64.50 stddev:16.88 (26.2%) stddev-sans-first:17.63 23:53:55 INFO - PROCESS | 2193 | Values: 78.0 73.0 57.0 109.0 57.0 56.0 59.0 70.0 81.0 56.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:85.50 stddev:12.56 (14.7%) stddev-sans-first:13.32 23:53:55 INFO - PROCESS | 2193 | Values: 81.0 90.0 92.0 72.0 69.0 68.0 94.0 69.0 67.0 99.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.89 (2.2%) stddev-sans-first:1.99 23:53:55 INFO - PROCESS | 2193 | Values: 87.0 84.0 87.0 85.0 87.0 84.0 88.0 86.0 90.0 85.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.00 stddev:2.37 (2.5%) stddev-sans-first:0.50 23:53:55 INFO - PROCESS | 2193 | Values: 100.0 92.0 93.0 93.0 92.0 93.0 93.0 93.0 93.0 92.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 23:53:55 INFO - PROCESS | 2193 | Values: 85.0 86.0 85.0 85.0 84.0 86.0 84.0 85.0 83.0 86.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:0.88 (1.6%) stddev-sans-first:0.87 23:53:55 INFO - PROCESS | 2193 | Values: 55.0 55.0 54.0 54.0 53.0 55.0 53.0 55.0 54.0 53.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.50 stddev:3.72 (5.3%) stddev-sans-first:3.08 23:53:55 INFO - PROCESS | 2193 | Values: 64.0 67.0 76.0 76.0 71.0 70.0 69.0 70.0 70.0 73.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:82.50 stddev:6.46 (7.8%) stddev-sans-first:6.86 23:53:55 INFO - PROCESS | 2193 | Values: 85.0 83.0 81.0 81.0 81.0 84.0 82.0 93.0 100.0 80.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:142.50 stddev:13.06 (9.2%) stddev-sans-first:13.78 23:53:55 INFO - PROCESS | 2193 | Values: 141.0 143.0 140.0 142.0 143.0 145.0 136.0 137.0 181.0 139.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:163.00 stddev:22.47 (13.8%) stddev-sans-first:23.62 23:53:55 INFO - PROCESS | 2193 | Values: 164.0 165.0 161.0 162.0 225.0 203.0 162.0 162.0 160.0 162.0 23:53:55 INFO - PROCESS | 2193 | 23:53:55 INFO - PROCESS | 2193 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:67.50 stddev:7.94 (11.8%) stddev-sans-first:7.84 23:53:55 INFO - PROCESS | 2193 | Values: 62.0 79.0 85.0 80.0 65.0 66.0 65.0 69.0 66.0 66.0 23:53:55 INFO - PROCESS | 2193 | -------- Summary: end -------- 23:53:55 INFO - PROCESS | 2193 | 23:53:56 INFO - TEST-INFO | 2193: exit 0 23:53:56 INFO - TEST-OK | kraken | took 58775ms 23:53:56 INFO - SUITE-END | took 1174s 23:53:56 INFO - Completed test suite (00:19:34) 23:53:56 INFO - javascript benchmark 23:53:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.3488269787385, 3339.6994270515647, 3339.916502087449, 3380.9661903380957, 3397.354502644497, 5602.943970560294, 5630.014747419201, 5713.200151978716, 5717.6283541569765, 5773.191753154561, 5719.19931209631, 5800.680962547058, 5802.274715660543, 5845.766169353232, 5941.197938278331, 5149.0, 5201.297824793661, 5204.193350030751, 5236.450172731866, 5241.5020573045595, 5171.482851714836, 5287.86780330491, 5289.656172348804, 5318.388385335678, 5327.840164795062, 5249.973750131263, 5263.947360526384, 5293.15309550472, 5301.363836339645, 5341.43914888935, 5076.974615126937, 5140.742962851842, 5158.071547121516, 5183.844484665466, 5201.9479805201845, 5088.363954505687, 5120.436751957282, 5144.125498665236, 5147.382314122292, 5199.0, 5647.548196144339, 5728.97135514324, 5794.420557944214, 5808.680522571257, 5828.6502809831545, 6283.0, 6288.591241569284, 6288.811335659938, 6297.559170858041, 6298.4646305064125, 6176.783812566584, 6178.907316390258, 6200.689965501707, 6254.718537665816, 6264.091706702548, 5900.909628348997, 5923.644581325091, 5949.0, 5961.791337303173, 5965.164876917235, 5315.574754019669, 6224.595401298901, 6293.0, 6293.118963345135, 6302.275238347557, 6029.849253768691, 6072.957203290619, 6092.979221685204, 6111.834843226386, 6125.723854043132, 6147.354527774609, 6212.4408803207925, 6216.689165541705, 6237.563370564017, 6277.403646653652, 5715.768514868304, 6178.814635560941, 6253.499720022387, 6258.43674069336, 6271.749130034747, 6188.752449901954, 6231.0, 6291.465225455888, 6296.087067375205, 6299.086632438362, 4865.9026819464125, 4920.852374428773, 4924.236743304794, 4964.975175124101, 4976.328195693535, 4328.0, 4506.7070640408265, 4526.343680166357, 4537.251353526631, 4541.955350269456, 5699.800506982236, 5712.685802280831, 5757.798477053283, 5762.078067509178, 5781.046127388934, 6228.201543368002, 6278.811635650938, 6284.654344011032, 6296.5592408531875, 6300.464460520907, 5593.776248950077, 5715.057015592381, 5726.284214473191, 5730.512906402919, 5753.85615359611, 5399.082156033483, 5399.892002160013, 5439.156930675752, 5449.264349312792, 5476.863078423071, 6288.591241569375, 6295.748170073238, 6302.0, 6304.65324407162, 6321.715522801393, 6135.263289243823, 6258.217722784652, 6294.842628934313, 6297.2128483939505, 6302.684865756785, 6214.689265536615, 6247.281562620356, 6271.278802937721, 6300.149479820349, 6309.747610095545, 6215.782447614311, 6221.4089661482085, 6224.284207316216, 6227.439530442282, 6227.844303892439, 6032.969835150796, 6211.068339749074, 6277.905831412624, 6304.495640348669, 6310.6529140895855, 6042.516598672008, 6096.207493026056, 6111.877762444815, 6119.541034422347, 6143.539234557336, 6044.607100538451, 6079.908801367895, 6106.816795496142, 6114.847128821815, 6129.754809807558, 5776.971115144397, 5842.883142337045, 5929.140274660236, 5966.105084237399, 6024.578279520391, 6054.848628784315, 6098.069176274567, 6134.509239260938, 6155.322914479493, 6176.258848938156, 5974.9103763444455, 5983.820485385445, 6000.429959153874, 6006.459418652167, 6052.515798736179], "value": 5717.202755450201, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.25164504887715, 528.7805560692312, 532.7932233491146, 533.5864704853738, 552.5055075707245, 40659.796701016494, 41492.37761433577, 41496.17007659845, 41569.0, 43461.7826910865, 34800.0, 36444.63555364445, 41926.0, 43007.99999999992, 43126.353104703376, 8823.470591764515, 8997.730068097968, 9846.655367062136, 10011.349262297963, 10024.14794742444, 10027.0, 12194.939025304906, 12214.938925305361, 12423.627291181234, 12425.378731063456, 12129.514819407257, 12142.757144857098, 12171.817422738623, 12196.329201893892, 12225.755484890342, 8914.019457859662, 9096.909030909672, 9105.408148470395, 9147.496887671176, 12053.035757139452, 11682.94158529202, 12019.278843269345, 12028.097892658086, 12171.0, 12194.939025304817, 41053.384199237036, 41223.587764122276, 41229.58770412317, 41304.38043429321, 41445.79277103626, 3541.274038822061, 3551.9112022199392, 3565.16218688608, 3672.4307732301536, 4205.768682722449, 31795.72817087314, 32192.356152877044, 32287.515687264477, 32528.186795330068, 32881.6711832881, 3293.8353082346025, 3294.6540613235506, 3295.24209431832, 3366.074329559366, 3384.4923261510726, 539700.0, 540409.0, 541104.0, 542767.0, 544292.0, 33683.49474757881, 57660.0, 57834.421655782906, 58317.4168258312, 58579.41420585739, 33599.66400336014, 35580.110497237256, 35598.82200588981, 35615.46576801298, 35629.465558016134, 14746.11523308605, 14909.776353354708, 14918.701625967637, 14948.476803311833, 14951.252437378087, 11544.307341559534, 11637.418129093512, 12570.87429125697, 12625.747485050248, 12651.430685619169, 6912.861742765217, 7718.228177182237, 7889.644965976545, 7923.049234091945, 7994.6002699864775, 2857.8856845725936, 3259.1363288728508, 3305.6694330567234, 3306.156929982839, 3329.1510664780326, 976.7899901521207, 993.6472552243994, 1066.8453074304182, 1078.347599702169, 1078.396098185019, 28925.132246032652, 30002.699973000428, 30413.056296785002, 31041.8447907759, 32073.679263207538, 930.6417029443713, 1024.2778840917135, 1037.9740506487399, 1080.8378743188584, 1101.8457415961773, 13546.86453135456, 15322.770158447633, 15424.691506169815, 15462.14958177311, 15895.205239738198, 714790.0, 720120.0, 723490.0, 725444.0, 726688.0, 55885.0, 56276.0, 56390.71804640951, 56419.43580564224, 56426.43573564294, 1285.6014635463036, 1310.698539335958, 1407.183833376639, 1411.2872999135782, 1457.5627311806415, 29787.191627211563, 30375.633096511152, 30445.0, 30784.53823192699, 31244.0, 48268.5173148264, 48564.2715359277, 49158.75420622874, 49200.0, 49268.52194434173, 577670.0, 579761.0, 585909.0, 586552.0, 589446.0, 22983.425414364774, 25659.717014148846, 25718.228453146436, 25797.3550661235, 25879.0, 45397.09205815931, 45519.0, 46036.30945535753, 46150.769246153555, 46553.53446465492, 29941.850290748407, 37361.0, 38950.80524597472, 39135.41296880606, 39425.2114957705, 36958.63041369552, 37739.43390849194, 37990.251218597674, 38080.619193808816, 38704.41943370908, 36251.63748362483, 36304.63695363013, 36622.8168859154, 36895.44656830203, 36922.63077369192, 30634.0, 32979.175520612174, 33618.831905840314, 33818.32363352768, 33841.83079084589, 24232.151874684303, 24470.755292446847, 24870.378240543407, 25407.745922540536, 25510.234692959242], "value": 19075.123506756376, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.8321771984887, 386.88200098969816, 389.95905429929854, 391.7590681730737, 416.5189206466532, 15207.391704331827, 17339.479815605522, 20712.792872071273, 20971.790282097176, 21510.354689359305, 19997.90011549364, 20605.896970515165, 20879.269225577107, 21273.361799146012, 21277.893610531926, 5825.359210486843, 5980.342162362137, 6048.2137322148055, 6050.485708714765, 6058.666773327465, 5787.565932555054, 5819.62172458791, 5942.257217847769, 5951.553633477485, 5955.9702201488935, 5791.333996590404, 5877.471027607515, 5893.469587737103, 5900.586958912856, 5903.940960590404, 5720.2849643794525, 5881.470667639933, 5894.233749612523, 5899.970500147493, 5903.468687818074, 4417.933730994037, 5028.396592408898, 5161.458046905059, 5165.22521621756, 5194.71429071401, 25660.35849103769, 25674.87162564194, 25980.0, 25989.480210395875, 26001.479970400676, 1890.914908829106, 2074.8962551872346, 2228.5320082782473, 2738.3291093682174, 3108.098651391094, 10144.442055686934, 10185.949070254675, 10218.591256349737, 10243.026912443305, 10355.378677279312, 1804.3053424431557, 1890.4706682129026, 1930.1217945834612, 1935.854810889189, 1941.7572803399576, 127346.0, 129027.35486322602, 132004.0, 133019.0, 138291.30854345864, 16251.618612418077, 19806.454192826033, 20553.89723051375, 20779.8961005197, 20832.37502874916, 34200.315993679986, 35111.64888351084, 35149.0, 35333.76331828425, 35385.82307088448, 10949.0, 10968.945155274172, 11106.777864442665, 11151.163662725328, 11303.378314192798, 9181.495017774087, 9188.678396256098, 9193.0, 9330.393524420891, 9355.204807591295, 9448.433094014381, 9532.094451027142, 9639.277054220962, 9677.322587418814, 9682.951585242028, 3611.5124458198334, 3637.781733096023, 3684.4393146763277, 3755.845090124254, 3900.932505771945, 883.4566741453959, 920.4983284110176, 936.6768464879606, 951.9000504946881, 968.2496065549115, 20006.799932000784, 20567.691484627743, 20850.58298834045, 21126.577468450545, 21200.469988250417, 831.8918540589684, 905.0496978172893, 947.2232769129248, 964.4454438697777, 974.3666616699202, 10895.945520272347, 11023.645555511364, 11098.944505277423, 11126.109911207084, 11162.330260184415, 584678.0, 592310.0, 601172.9941350265, 621296.0, 626892.0, 101536.0, 107729.46135269273, 117619.0, 131542.0, 133059.60159044192, 978.6183388478499, 980.6861804222581, 1009.4498498318434, 1011.4942528735633, 1012.372329155928, 15280.847191527942, 18234.270629175215, 18740.625187496447, 18904.716429253847, 19431.611367772282, 52164.478355215964, 54164.45835541594, 55349.72325138348, 56035.59911002094, 56863.71568142133, 67841.0, 73437.63281183774, 75659.24340756742, 84089.0, 89029.0, 7691.000169977867, 8053.35573154158, 8162.42862999608, 8185.386096042702, 8263.00843898736, 8816.162300249465, 10558.841617375592, 12604.180728252633, 12614.306213158365, 12954.546590869275, 10946.616868409568, 10953.233273670767, 11043.061339785887, 11285.21003529745, 11431.0, 10772.946135269274, 11011.559537618727, 11031.944840275748, 11068.778624427627, 11148.665540033811, 5585.301837270341, 11354.148438866952, 11394.886051139383, 11429.257098288585, 11751.0, 7938.166492518202, 7974.920250797418, 8046.597670116354, 8068.314193293401, 8183.3453323732765, 10982.231243813176, 10985.670429887115, 11047.337159770439, 11145.83281250798, 11295.661130166109], "value": 10419.136233322899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1546.5360391882432, 1587.3967892200963, 1805.6298458815943, 1931.2854243929748, 1954.0620502158963, 8817.823643527126, 9050.547472626358, 9212.861807072892, 9242.353035287533, 9288.907110928889, 8029.397795165357, 8276.462029968048, 8690.391672582922, 8954.32842536809, 8994.280457563396, 2415.1064106280655, 2447.645091461737, 2448.375664205634, 2448.865312407817, 2451.142100264911, 2413.925803017658, 2423.660687503751, 2459.594166962458, 2461.9753802462014, 2462.052109937669, 2141.635921893282, 2217.846719705752, 2353.8352315337934, 2412.2039726890084, 2418.4679370538543, 2175.1516908405733, 2407.350015495815, 2421.9394515137265, 2430.6354046892843, 2434.513097380513, 2015.6875684268816, 2395.2574701842486, 2397.4006498375406, 2412.457197130649, 2417.6736140620965, 8946.597403116875, 9033.774155646095, 9041.457512549268, 9050.5927233274, 9058.275337972946, 5339.786408543654, 5510.9448905510835, 5541.224228607998, 5544.86137846553, 5547.916781248285, 3630.2591543384574, 3691.151035261905, 3749.906252343686, 3760.623937606245, 3849.8460061597784, 1117.608836907076, 1122.0, 1129.0628778114146, 1135.0125390909961, 1143.1997601678859, 10999.680038395363, 11563.826542601952, 12114.636560903187, 12275.754484910252, 12314.938425307817, 4478.910421791546, 4631.791569379386, 4699.295105734099, 4705.435347758223, 4707.7881495332795, 9478.38390504615, 9481.241500680067, 9484.762880928032, 9485.383450075724, 9526.714198574055, 5379.515843574097, 5386.192071189353, 5387.111126664135, 5387.4073851876265, 5391.56867450603, 4908.828191013297, 5040.672356296829, 5045.31888195089, 5050.898982020412, 5125.56432703217, 3322.2192784695576, 3847.3074846527893, 3882.281777871103, 3883.0292426893275, 3923.6272554107313, 1100.6973082402403, 1126.718320419895, 1170.6253998720329, 1181.4860535667012, 1184.3367714080143, 710.9822254443577, 788.984220315602, 829.9211025666609, 853.6201390381345, 940.4639355567261, 6110.602810817282, 6255.937440625627, 6302.684865756785, 6311.431971122621, 6327.462165715873, 877.9385443018928, 962.6245764151986, 979.5298256836759, 987.1707765477033, 1015.5836107196014, 4061.5532291447917, 4124.546299907008, 4132.938005929914, 4188.162367526507, 4213.641840443535, 10735.194860385502, 10987.945060274647, 11165.888341116648, 11184.105271578253, 11203.047740942111, 10123.1901447884, 10674.57301707923, 10975.231733778719, 11088.445577721082, 11100.44497775108, 8295.294899933573, 8310.8337833243, 8520.0, 8539.231469167804, 8549.914500855037, 6140.8771824564155, 6160.907586386118, 6176.722047507783, 6186.1648677427975, 6239.1577137085915, 10498.632547860789, 10521.05310522026, 10525.684229473129, 10534.42060686639, 10544.367337959748, 10016.348937319051, 10337.17302615804, 10613.469326533488, 10747.247692661438, 10777.676669699922, 7702.345300649507, 7759.72840950564, 7849.058113026245, 7855.0, 7859.449838511478, 7759.534427934342, 7916.6437510311025, 8457.196566326189, 8457.83084338322, 8558.486490810572, 7541.5475071495885, 7623.542587444771, 7672.347850432552, 7685.346745526469, 7748.728794492165, 6860.5197636164985, 7149.785506434815, 7506.549607023596, 7507.924920750941, 7634.122075961113, 6092.786752463642, 6975.476839236976, 7088.929110708827, 7162.140543134858, 7163.212046674757, 5079.466656001215, 5098.0, 5120.308758317579, 5233.712145832016, 5261.947380526146, 5054.620903432331, 5066.996066058521, 5168.612354073384, 5182.559482444034, 5279.419263881046], "value": 4673.342946763696, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.9721925026748, 313.3810723820455, 317.4523946192818, 320.2250553660141, 320.7402004376457, 4970.70175789452, 5459.7816087356505, 5486.670799752018, 5501.239603326932, 5543.944560554393, 4323.610875021247, 4773.06925149596, 5103.719295438749, 5466.200990311735, 5479.4520547945185, 3423.6747508986605, 3918.686505079601, 3948.539040554999, 4067.5932406759234, 4114.218298523286, 3403.7957722536726, 3463.8614455421753, 4061.979690101545, 4153.833846646131, 4191.874243772691, 3422.19578399076, 4079.9192411869863, 4083.3262511685534, 4114.753114813106, 4129.926219183019, 3329.933401331984, 3495.860165593386, 3840.0783811885085, 3932.5280966283945, 4122.773247471388, 2982.209714425679, 3552.769070010441, 3575.5173051637958, 3580.910477238064, 3626.184108575576, 4368.0, 5285.814996475105, 5299.019681358961, 5365.4098049214945, 5384.1923711443205, 4588.810776510327, 4740.19416699162, 4750.596199323062, 4769.4515130760055, 4773.689710168863, 5594.888102237973, 5617.4944255016835, 5661.207430959669, 5671.432856714337, 5701.515371193454, 3501.8074005929666, 3987.1626958338784, 4002.519697636273, 4020.155767288873, 4024.3762216856435, 5418.403975562725, 5672.4611161939965, 5864.560157988168, 6017.946859299605, 6055.939440605582, 3826.693864490834, 3987.2623564640635, 3990.660793832557, 4011.5357894368344, 4021.2158629067635, 3467.7225821934185, 4084.81618327176, 4096.160287141129, 4117.485314335708, 4121.649659778893, 3933.1347103637154, 4357.4988876279, 4371.038371558252, 4754.667173297905, 4757.35775670287, 5345.599080069011, 5528.977139229255, 5718.799052199085, 5790.739416726257, 5809.862674155993, 1322.9073964822562, 1352.2832898563777, 1356.379126943313, 1369.3084992079137, 1371.6626289367987, 764.9464537482434, 792.469045739356, 814.9877751833727, 821.843849668561, 826.5330088499978, 3962.8018599069933, 4507.459104907367, 4693.647976401783, 5241.764120614582, 5336.599755018389, 4976.576990955737, 5253.080710875582, 5638.943610563924, 5735.655860648374, 5940.0, 4693.06138772247, 5071.467495912876, 5289.18017707256, 5318.627696061239, 5363.678179309254, 3026.379592183597, 3037.0, 3068.6831442261396, 3082.9845850770603, 3091.6753740857334, 5462.7268636568015, 5903.35063143054, 5963.761449542057, 5986.0, 6163.2573504790835, 5197.350331208599, 5267.394249661262, 5493.862653433616, 5560.221568980346, 5731.197632331477, 5975.19334889793, 5989.78378000134, 6037.335893051761, 6040.75836966534, 6045.969770151209, 5114.4374118847645, 5126.7180305083575, 5525.336959564878, 5821.661017965806, 5831.375880853548, 5453.972730136324, 5679.432056794452, 5712.685802280748, 5716.857078573069, 5792.855178620568, 5056.84829455117, 5162.741862906914, 5914.467697907209, 6037.396260373927, 6039.849003774941, 3281.721053710366, 3693.4829123922136, 3819.9045023874623, 3829.8659546915724, 3876.4572959786215, 4459.598636122764, 5665.3004098771025, 5789.768409263583, 5798.0, 5833.82498525045, 5131.127708289674, 5638.718064096696, 5670.53331800668, 5670.858228544319, 5718.3423906249645, 5283.365996080495, 5596.852645207641, 5626.212330273846, 5721.223992165151, 5778.768849245983, 5343.812966546152, 5475.342958844964, 5501.807436739695, 5504.256925315031, 5542.556595472433, 4110.712250142461, 4957.909259962802, 5071.18860982241, 5543.9168412474655, 5569.35952365467, 4549.7042692224895, 4927.304521608793, 5620.662760234399, 5647.802326918702, 5675.347335056521], "value": 4100.5187501101855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.5653377574782, 789.1516619633895, 842.7303262955849, 852.812381276119, 857.3624377439093, 15210.0, 15718.842811571882, 15738.763918541232, 15768.053916765002, 16403.58991025225, 14849.480268190615, 15053.096814191156, 15058.548243552683, 15080.321385537649, 15085.698286034272, 710.7157137145132, 728.9963550182242, 736.1571001203619, 910.8314961732068, 914.2366124286195, 782.3232903538427, 865.9956700216491, 878.9648414063462, 879.9912000880005, 880.3925291548843, 954.8233576788316, 954.89973552777, 956.5312996631635, 956.8373376526007, 958.2334132693852, 753.9660715267826, 808.8827120067616, 919.1314208073373, 943.938643988142, 961.1061712607273, 705.1397295299727, 706.5901776969346, 706.9293070692889, 708.6633848921772, 710.474249055695, 15013.699726005529, 15081.54755357341, 15082.24588770557, 15095.622609434742, 15107.697846043127, 13837.654058648513, 13971.79042314366, 13972.510962116277, 14082.647933801634, 14140.575782726535, 11212.27120237182, 11599.478023488879, 11949.581764638197, 11971.401429928555, 12657.620271391874, 4690.413698287714, 4711.528847115295, 4740.525947405266, 4881.609471242292, 4934.851954441372, 104008.47995759973, 120654.0, 121497.0, 122720.38639806834, 123534.76465235323, 11243.046906400523, 11476.88523114775, 11526.538938442536, 11697.532098716127, 11843.822342664867, 21654.566908661738, 21760.782392176192, 21796.673049904264, 22149.0, 22164.113435462725, 7958.283754462126, 8057.556834374166, 8070.838583228303, 8138.308243799225, 8204.589770511451, 4476.239039363316, 4499.955000450019, 4512.571305725951, 4566.06395688883, 4661.743604101739, 3556.8043757593064, 3666.871659491905, 3670.743047986669, 3675.0, 3675.72432067596, 562.3083607163212, 570.8201916396322, 571.665575638248, 600.2376981233808, 660.8050625065617, 582.248898920388, 583.9416058394212, 633.6609913696151, 655.2268323378364, 657.4674513643964, 9067.637294508279, 9108.81782364349, 9294.72115836526, 9390.154886060287, 9413.48225847585, 630.4294613374904, 646.8188907106017, 657.763205246111, 661.5732852310233, 662.8276648071536, 4367.694261401672, 4437.911241775147, 4463.196624607602, 4499.4150760400935, 4505.48186958504, 139002.3049884744, 148829.25585372004, 151833.0, 157737.2113139427, 159086.0, 82385.58807205925, 110764.0, 115544.0, 116848.0, 119697.0, 109309.45345273222, 116420.83579164269, 118716.0, 120037.99999999825, 121124.3943780293, 8711.433756805787, 8731.607077681647, 8788.121187881161, 8825.514596697243, 8925.0, 55651.33046008626, 56825.43174568201, 65044.349556505724, 65949.67025164844, 66049.0, 69201.61596767936, 85311.57344213488, 98705.0, 99753.0, 100668.0, 11449.713757156138, 11484.655460336204, 11634.418279086181, 11711.238769479956, 11963.102767292661, 12521.37393130358, 12527.248365098123, 12667.0, 12678.302693351954, 12962.157459765085, 11969.461374238006, 12002.21985570935, 12015.098867584791, 12425.13024088305, 12446.626601201979, 11583.247088939192, 11963.282203067844, 12116.818247726114, 12240.265584064628, 12488.875111248772, 11731.88268117342, 11919.165658403828, 12025.819612705642, 12057.336846473529, 12299.323537205539, 10012.849807253042, 10032.147267482345, 10072.143867771321, 10326.741831453974, 10345.275181723588, 9013.459192448474, 9192.334223075233, 9341.626334946526, 9379.202767764544, 10099.798004040025], "value": 7722.745920264757, "unit": "score"}], "name": "dromaeo_css", "value": 7429.523082058897, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 107.0, 156.0, 107.0, 108.0, 129.0, 109.0, 138.0, 107.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 185.0, 184.0, 183.0, 179.0, 181.0, 181.0, 182.0, 184.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 57.0, 109.0, 57.0, 56.0, 59.0, 70.0, 81.0, 56.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 90.0, 92.0, 72.0, 69.0, 68.0, 94.0, 69.0, 67.0, 99.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 87.0, 84.0, 88.0, 86.0, 90.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 85.0, 84.0, 86.0, 84.0, 85.0, 83.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 54.0, 53.0, 55.0, 53.0, 55.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 76.0, 76.0, 71.0, 70.0, 69.0, 70.0, 70.0, 73.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 81.0, 81.0, 81.0, 84.0, 82.0, 93.0, 100.0, 80.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 140.0, 142.0, 143.0, 145.0, 136.0, 137.0, 181.0, 139.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 161.0, 162.0, 225.0, 203.0, 162.0, 162.0, 160.0, 162.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 79.0, 85.0, 80.0, 65.0, 66.0, 65.0, 69.0, 66.0, 66.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1427.2999999999997, "alertThreshold": 2.0}]} 23:53:56 INFO - Return code: 0 23:53:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:53:56 INFO - # TBPL SUCCESS # 23:53:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:53:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:53:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:53:56 INFO - 2016-07-06 23:53:56.850 screenresolution[2197:22926] starting screenresolution argv=screenresolution get 23:53:56 INFO - 2016-07-06 23:53:56.853 screenresolution[2197:22926] Display 0: 1600x1200x32@60 23:53:56 INFO - 2016-07-06 23:53:56.859 screenresolution[2198:22929] starting screenresolution argv=screenresolution list 23:53:56 INFO - Available Modes on Display 0 23:53:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:53:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:53:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:53:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:53:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:53:57 INFO - Intel Iris: 23:53:57 INFO - Chipset Model: Intel Iris 23:53:57 INFO - Type: GPU 23:53:57 INFO - Bus: Built-In 23:53:57 INFO - VRAM (Dynamic, Max): 1536 MB 23:53:57 INFO - Vendor: Intel (0x8086) 23:53:57 INFO - Device ID: 0x0a2e 23:53:57 INFO - Revision ID: 0x0009 23:53:57 INFO - Displays: 23:53:57 INFO - Display: 23:53:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:53:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:53:57 INFO - Display Serial Number: 1600x1200 60 23:53:57 INFO - Main Display: Yes 23:53:57 INFO - Mirror: Off 23:53:57 INFO - Online: Yes 23:53:57 INFO - Rotation: Supported 23:53:57 INFO - Return code: 0 23:53:57 INFO - Running post-action listener: _resource_record_post_action 23:53:57 INFO - ##### 23:53:57 INFO - ##### Finished run-tests step (success) 23:53:57 INFO - ##### 23:53:57 INFO - Running post-run listener: _resource_record_post_run 23:53:57 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 380928; Write bytes: 705131008; Read time: 4; Write time: 2304 23:53:57 INFO - TinderboxPrint: CPU usage
53.6% 23:53:57 INFO - TinderboxPrint: I/O read bytes / time
380,928 / 4 23:53:57 INFO - TinderboxPrint: I/O write bytes / time
705,131,008 / 2,304 23:53:57 INFO - TinderboxPrint: CPU idle
2,248.8 (46.4%) 23:53:57 INFO - TinderboxPrint: CPU system
963.4 (19.9%) 23:53:57 INFO - TinderboxPrint: CPU user
1,629.5 (33.7%) 23:53:57 INFO - TinderboxPrint: Swap in / out
406,454,272 / 0 23:53:57 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 404447744; Write bytes: 399988224; Read time: 30469; Write time: 982 23:53:57 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 299008; Write bytes: 293510144; Read time: 3; Write time: 1288 23:53:57 INFO - Running post-run listener: _upload_blobber_files 23:53:57 INFO - Blob upload gear active. 23:53:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:53:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:53:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:53:57 INFO - (blobuploader) - INFO - Open directory for files ... 23:53:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:53:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:53:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:53:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:53:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:53:58 INFO - (blobuploader) - INFO - Done attempting. 23:53:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:53:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:53:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:53:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:53:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:53:59 INFO - (blobuploader) - INFO - Done attempting. 23:53:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:53:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:53:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:54:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:54:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:54:01 INFO - (blobuploader) - INFO - Done attempting. 23:54:01 INFO - (blobuploader) - INFO - Iteration through files over. 23:54:01 INFO - Return code: 0 23:54:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:54:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:54:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c8c5441bd7e7f4d64509928d157ab9f17fd7e93efc4fda935d4117b41c41f8039084de57dd2720018140dea2dba06e3e559e7cb116004b9dafa4ae862d1df46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26f01a6eab67c829353543150968109b02000f5ea6047c6b5c8c685af887a94296cb67c73f1190d66446045579ab7eca1c6f6ada65c9faff66e0e89e36aa38bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d8ccfdf9f602d25c5f7b53b8c542c4a3ebfad3a6dd071ea7e58e384a66bb7eb3e83751cdf54764b072fa4d7ddeaa396033622c8c920e80f50f499f194bfe409"} 23:54:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:54:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:54:01 INFO - Contents: 23:54:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c8c5441bd7e7f4d64509928d157ab9f17fd7e93efc4fda935d4117b41c41f8039084de57dd2720018140dea2dba06e3e559e7cb116004b9dafa4ae862d1df46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26f01a6eab67c829353543150968109b02000f5ea6047c6b5c8c685af887a94296cb67c73f1190d66446045579ab7eca1c6f6ada65c9faff66e0e89e36aa38bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d8ccfdf9f602d25c5f7b53b8c542c4a3ebfad3a6dd071ea7e58e384a66bb7eb3e83751cdf54764b072fa4d7ddeaa396033622c8c920e80f50f499f194bfe409"} 23:54:01 INFO - Running post-run listener: copy_logs_to_upload_dir 23:54:01 INFO - Copying logs to upload dir... 23:54:01 INFO - mkdir: /builds/slave/test/build/upload/logs 23:54:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.600549 ========= master_lag: 63.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-07-06 23:55:05.071806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 23:55:05.073008) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vKG06Ka3ab/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c8c5441bd7e7f4d64509928d157ab9f17fd7e93efc4fda935d4117b41c41f8039084de57dd2720018140dea2dba06e3e559e7cb116004b9dafa4ae862d1df46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26f01a6eab67c829353543150968109b02000f5ea6047c6b5c8c685af887a94296cb67c73f1190d66446045579ab7eca1c6f6ada65c9faff66e0e89e36aa38bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d8ccfdf9f602d25c5f7b53b8c542c4a3ebfad3a6dd071ea7e58e384a66bb7eb3e83751cdf54764b072fa4d7ddeaa396033622c8c920e80f50f499f194bfe409"} build_url:https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011000 build_url: 'https://queue.taskcluster.net/v1/task/KloO7xbzSNmtO57FwkH_bg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c8c5441bd7e7f4d64509928d157ab9f17fd7e93efc4fda935d4117b41c41f8039084de57dd2720018140dea2dba06e3e559e7cb116004b9dafa4ae862d1df46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26f01a6eab67c829353543150968109b02000f5ea6047c6b5c8c685af887a94296cb67c73f1190d66446045579ab7eca1c6f6ada65c9faff66e0e89e36aa38bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d8ccfdf9f602d25c5f7b53b8c542c4a3ebfad3a6dd071ea7e58e384a66bb7eb3e83751cdf54764b072fa4d7ddeaa396033622c8c920e80f50f499f194bfe409"}' ========= master_lag: 0.67 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 23:55:05.755022) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 11 secs) (at 2016-07-06 23:55:05.755343) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vKG06Ka3ab/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.iteOo2sEBG/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005176 ========= master_lag: 11.25 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 11 secs) (at 2016-07-06 23:55:17.009471) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2016-07-06 23:55:17.010403) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2016-07-06 23:55:23.135342) ========= ========= Total master_lag: 75.10 =========