builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0264 starttime: 1489539825.24 results: success (0) buildid: 20170314164336 builduid: 0b1bc1af44f9429aadf59f8ea3fb097d revision: fd8bdabb4813164e70b51d02b420e3659eb38536 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:45.235824) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:45.236279) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:45.236591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:45.264502) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-14 18:03:45.264816) ========= 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.3dDRbDki4r/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.NEYyZwBvZW/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 --2017-03-14 18:03:45-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.86M=0.001s 2017-03-14 18:03:45 (7.86 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108263 ========= master_lag: 3.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-14 18:03:48.655393) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:48.655793) ========= 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.3dDRbDki4r/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.NEYyZwBvZW/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.020376 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:48.704227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:48.705049) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd8bdabb4813164e70b51d02b420e3659eb38536 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd8bdabb4813164e70b51d02b420e3659eb38536 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3dDRbDki4r/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.NEYyZwBvZW/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 2017-03-14 18:03:48,795 truncating revision to first 12 chars 2017-03-14 18:03:48,795 Setting DEBUG logging. 2017-03-14 18:03:48,795 attempt 1/10 2017-03-14 18:03:48,795 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fd8bdabb4813?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-14 18:03:49,444 unpacking tar archive at: mozilla-aurora-fd8bdabb4813/testing/mozharness/ program finished with exit code 0 elapsedTime=0.910662 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:49.640747) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:49.641075) ========= script_repo_revision: fd8bdabb4813164e70b51d02b420e3659eb38536 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:49.641509) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:49.641852) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:49.661656) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-14 18:03:49.662063) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3dDRbDki4r/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.NEYyZwBvZW/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:03:49 INFO - MultiFileLogger online at 20170314 18:03:49 in /builds/slave/test 18:03:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:03:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:03:49 INFO - {'append_to_log': False, 18:03:49 INFO - 'base_work_dir': '/builds/slave/test', 18:03:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:03:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:03:49 INFO - 'branch': 'Mozilla-Aurora', 18:03:49 INFO - 'buildbot_json_path': 'buildprops.json', 18:03:49 INFO - 'config_files': ('talos/mac_config.py',), 18:03:49 INFO - 'default_actions': ('clobber', 18:03:49 INFO - 'read-buildbot-config', 18:03:49 INFO - 'download-and-extract', 18:03:49 INFO - 'populate-webroot', 18:03:49 INFO - 'create-virtualenv', 18:03:49 INFO - 'install', 18:03:49 INFO - 'run-tests'), 18:03:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:03:49 INFO - 'download_minidump_stackwalk': True, 18:03:49 INFO - 'download_symbols': 'ondemand', 18:03:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:03:49 INFO - 'tooltool.py': '/tools/tooltool.py', 18:03:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:03:49 INFO - '/tools/misc-python/virtualenv.py')}, 18:03:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:03:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:03:49 INFO - 'gecko_profile': False, 18:03:49 INFO - 'gecko_profile_interval': 0, 18:03:49 INFO - 'installer_path': 'installer.exe', 18:03:49 INFO - 'log_level': 'info', 18:03:49 INFO - 'log_name': 'talos', 18:03:49 INFO - 'log_to_console': True, 18:03:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:03:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:03:49 INFO - 'opt_config_files': (), 18:03:49 INFO - 'pip_index': False, 18:03:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:49 INFO - 'cmd': ('bash', 18:03:49 INFO - '-c', 18:03:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:49 INFO - 'enabled': True, 18:03:49 INFO - 'halt_on_failure': False, 18:03:49 INFO - 'name': 'check_screen_resolution'},), 18:03:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:49 INFO - 'cmd': ('bash', 18:03:49 INFO - '-c', 18:03:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:49 INFO - 'enabled': True, 18:03:49 INFO - 'halt_on_failure': False, 18:03:49 INFO - 'name': 'check_screen_resolution'},), 18:03:49 INFO - 'run_cmd_checks_enabled': True, 18:03:49 INFO - 'suite': 'dromaeojs-e10s', 18:03:49 INFO - 'system_bits': '32', 18:03:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:03:49 INFO - 'title': 't-yosemite-r7-0264', 18:03:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:03:49 INFO - 'use_talos_json': True, 18:03:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:03:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:03:49 INFO - 'work_dir': 'build'} 18:03:49 INFO - [mozharness: 2017-03-15 01:03:49.872541Z] Running clobber step. 18:03:49 INFO - Running pre-action listener: _resource_record_pre_action 18:03:49 INFO - Running main action method: clobber 18:03:49 INFO - rmtree: /builds/slave/test/build 18:03:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:03:51 INFO - Running post-action listener: _resource_record_post_action 18:03:51 INFO - [mozharness: 2017-03-15 01:03:51.554016Z] Finished clobber step (success) 18:03:51 INFO - [mozharness: 2017-03-15 01:03:51.554160Z] Running read-buildbot-config step. 18:03:51 INFO - Running pre-action listener: _resource_record_pre_action 18:03:51 INFO - Running main action method: read_buildbot_config 18:03:51 INFO - Using buildbot properties: 18:03:51 INFO - { 18:03:51 INFO - "project": "", 18:03:51 INFO - "product": "firefox", 18:03:51 INFO - "script_repo_revision": "production", 18:03:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 18:03:51 INFO - "repository": "", 18:03:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 18:03:51 INFO - "buildid": "20170314164336", 18:03:51 INFO - "pgo_build": "False", 18:03:51 INFO - "basedir": "/builds/slave/test", 18:03:51 INFO - "buildnumber": 20, 18:03:51 INFO - "slavename": "t-yosemite-r7-0264", 18:03:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:03:51 INFO - "platform": "yosemite_r7", 18:03:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:03:51 INFO - "branch": "mozilla-aurora", 18:03:51 INFO - "repo_path": "releases/mozilla-aurora", 18:03:51 INFO - "revision": "fd8bdabb4813164e70b51d02b420e3659eb38536", 18:03:51 INFO - "stage_platform": "macosx64", 18:03:51 INFO - "builduid": "0b1bc1af44f9429aadf59f8ea3fb097d", 18:03:51 INFO - "slavebuilddir": "test" 18:03:51 INFO - } 18:03:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 18:03:51 INFO - Running post-action listener: _resource_record_post_action 18:03:51 INFO - [mozharness: 2017-03-15 01:03:51.557466Z] Finished read-buildbot-config step (success) 18:03:51 INFO - [mozharness: 2017-03-15 01:03:51.557584Z] Running download-and-extract step. 18:03:51 INFO - Running pre-action listener: _resource_record_pre_action 18:03:51 INFO - Running main action method: download_and_extract 18:03:51 INFO - mkdir: /builds/slave/test/build/tests 18:03:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:51 INFO - trying https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 18:03:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:03:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 18:03:52 INFO - Downloaded 1199 bytes. 18:03:52 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:03:52 INFO - Using the following test package requirements: 18:03:52 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:03:52 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 18:03:52 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 18:03:52 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'jsshell-mac.zip'], 18:03:52 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 18:03:52 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:03:52 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 18:03:52 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 18:03:52 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 18:03:52 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:52 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 18:03:52 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:03:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:03:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:03:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:03:55 INFO - Expected file size: 51780715 18:03:55 INFO - Obtained file size: 51780715 18:04:11 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:04:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:04:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:04:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:04:13 INFO - Expected file size: 51780715 18:04:13 INFO - Obtained file size: 51780715 18:04:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 18:04:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:04:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 18:04:29 INFO - Expected file size: 14404424 18:04:29 INFO - Obtained file size: 14404424 18:04:29 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:29 INFO - trying https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:04:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 18:04:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 18:04:32 INFO - Downloaded 74627176 bytes. 18:04:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:04:32 INFO - mkdir: /builds/slave/test/properties 18:04:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:32 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:32 INFO - Contents: 18:04:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:04:32 INFO - Running post-action listener: _resource_record_post_action 18:04:32 INFO - Running post-action listener: set_extra_try_arguments 18:04:32 INFO - [mozharness: 2017-03-15 01:04:32.683004Z] Finished download-and-extract step (success) 18:04:32 INFO - [mozharness: 2017-03-15 01:04:32.683145Z] Running populate-webroot step. 18:04:32 INFO - Running pre-action listener: _resource_record_pre_action 18:04:32 INFO - Running main action method: populate_webroot 18:04:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:32 INFO - '--tppagecycles', 18:04:32 INFO - '7'], 18:04:32 INFO - 'tests': ['tsvgm']}}, 18:04:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:04:32 INFO - 'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:04:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:04:32 INFO - 'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['damp', 'tps']}, 18:04:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:04:32 INFO - 'tests': ['damp', 'tps']}, 18:04:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['dromaeo_dom']}, 18:04:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['basic_compositor_video']}, 18:04:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:04:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['a11yr', 18:04:32 INFO - 'ts_paint', 18:04:32 INFO - 'tpaint', 18:04:32 INFO - 'sessionrestore', 18:04:32 INFO - 'sessionrestore_no_auto_restore', 18:04:32 INFO - 'tabpaint']}, 18:04:32 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:32 INFO - 'ts_paint', 18:04:32 INFO - 'tpaint', 18:04:32 INFO - 'sessionrestore', 18:04:32 INFO - 'sessionrestore_no_auto_restore', 18:04:32 INFO - 'tabpaint']}, 18:04:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:04:32 INFO - 'ts_paint', 18:04:32 INFO - 'tpaint', 18:04:32 INFO - 'sessionrestore', 18:04:32 INFO - 'sessionrestore_no_auto_restore', 18:04:32 INFO - 'tabpaint']}, 18:04:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:04:32 INFO - 'ts_paint', 18:04:32 INFO - 'tpaint', 18:04:32 INFO - 'sessionrestore', 18:04:32 INFO - 'sessionrestore_no_auto_restore', 18:04:32 INFO - 'tabpaint']}, 18:04:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['a11yr', 18:04:32 INFO - 'ts_paint', 18:04:32 INFO - 'tpaint', 18:04:32 INFO - 'sessionrestore', 18:04:32 INFO - 'sessionrestore_no_auto_restore', 18:04:32 INFO - 'tabpaint']}, 18:04:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['a11yr', 18:04:32 INFO - 'ts_paint', 18:04:32 INFO - 'tpaint', 18:04:32 INFO - 'sessionrestore', 18:04:32 INFO - 'sessionrestore_no_auto_restore', 18:04:32 INFO - 'tabpaint']}, 18:04:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['tsvgx', 18:04:32 INFO - 'tsvgr_opacity', 18:04:32 INFO - 'tart', 18:04:32 INFO - 'tscrollx', 18:04:32 INFO - 'cart', 18:04:32 INFO - 'tsvg_static']}, 18:04:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:32 INFO - 'tsvgr_opacity', 18:04:32 INFO - 'tart', 18:04:32 INFO - 'tscrollx', 18:04:32 INFO - 'cart', 18:04:32 INFO - 'tsvg_static']}, 18:04:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:04:32 INFO - 'talos_options': ['--disable-e10s'], 18:04:32 INFO - 'tests': ['tp5o']}, 18:04:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:04:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:04:32 INFO - 'talos_options': ['--disable-e10s', 18:04:32 INFO - '--xperf_path', 18:04:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:32 INFO - 'tests': ['tp5n']}, 18:04:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:04:32 INFO - 'talos_options': ['--xperf_path', 18:04:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:32 INFO - 'tests': ['tp5n']}}, 18:04:32 INFO - 'talos.zip': {'path': '', 18:04:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:32 INFO - Running post-action listener: _resource_record_post_action 18:04:32 INFO - [mozharness: 2017-03-15 01:04:32.701758Z] Finished populate-webroot step (success) 18:04:32 INFO - [mozharness: 2017-03-15 01:04:32.701897Z] Running create-virtualenv step. 18:04:32 INFO - Running pre-action listener: _resource_record_pre_action 18:04:32 INFO - Running main action method: create_virtualenv 18:04:32 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:32 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 18:04:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:04:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:32 INFO - Using real prefix '/tools/python27' 18:04:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:33 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:36 INFO - Installing pip.................done. 18:04:36 INFO - Return code: 0 18:04:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:04:36 INFO - Reading from file tmpfile_stdout 18:04:36 INFO - Output received: 18:04:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:04:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:36 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04:36 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 18:04:36 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 18:04:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:36 INFO - 'HOME': '/Users/cltbld', 18:04:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:36 INFO - 'LOGNAME': 'cltbld', 18:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:36 INFO - 'MOZ_NO_REMOTE': '1', 18:04:36 INFO - 'NO_EM_RESTART': '1', 18:04:36 INFO - 'PAGER': '/bin/cat', 18:04:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:36 INFO - 'PWD': '/builds/slave/test', 18:04:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:36 INFO - 'SHELL': '/bin/bash', 18:04:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:36 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:36 INFO - 'USER': 'cltbld', 18:04:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:36 INFO - 'XPC_FLAGS': '0x0', 18:04:36 INFO - 'XPC_SERVICE_NAME': '0', 18:04:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:04:36 INFO - Cleaning up... 18:04:36 INFO - Return code: 0 18:04:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:36 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04:36 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 18:04:36 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 18:04:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:36 INFO - 'HOME': '/Users/cltbld', 18:04:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:36 INFO - 'LOGNAME': 'cltbld', 18:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:36 INFO - 'MOZ_NO_REMOTE': '1', 18:04:36 INFO - 'NO_EM_RESTART': '1', 18:04:36 INFO - 'PAGER': '/bin/cat', 18:04:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:36 INFO - 'PWD': '/builds/slave/test', 18:04:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:36 INFO - 'SHELL': '/bin/bash', 18:04:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:36 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:36 INFO - 'USER': 'cltbld', 18:04:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:36 INFO - 'XPC_FLAGS': '0x0', 18:04:36 INFO - 'XPC_SERVICE_NAME': '0', 18:04:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:37 INFO - Downloading/unpacking psutil>=3.1.1 18:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:04:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:40 INFO - Installing collected packages: psutil 18:04:40 INFO - Running setup.py install for psutil 18:04:40 INFO - building 'psutil._psutil_osx' extension 18:04:40 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 18:04:40 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 18:04:41 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 18:04:41 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 18:04:41 INFO - building 'psutil._psutil_posix' extension 18:04:41 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 18:04:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:04:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:04:41 INFO - ^ 18:04:41 INFO - 1 warning generated. 18:04:41 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 18:04:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:41 INFO - Successfully installed psutil 18:04:41 INFO - Cleaning up... 18:04:41 INFO - Return code: 0 18:04:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:42 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04:42 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 18:04:42 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 18:04:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:42 INFO - 'HOME': '/Users/cltbld', 18:04:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:42 INFO - 'LOGNAME': 'cltbld', 18:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:42 INFO - 'MOZ_NO_REMOTE': '1', 18:04:42 INFO - 'NO_EM_RESTART': '1', 18:04:42 INFO - 'PAGER': '/bin/cat', 18:04:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:42 INFO - 'PWD': '/builds/slave/test', 18:04:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:42 INFO - 'SHELL': '/bin/bash', 18:04:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:42 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:42 INFO - 'USER': 'cltbld', 18:04:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:42 INFO - 'XPC_FLAGS': '0x0', 18:04:42 INFO - 'XPC_SERVICE_NAME': '0', 18:04:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:04:45 INFO - Installing collected packages: mozsystemmonitor 18:04:45 INFO - Running setup.py install for mozsystemmonitor 18:04:45 INFO - Successfully installed mozsystemmonitor 18:04:45 INFO - Cleaning up... 18:04:45 INFO - Return code: 0 18:04:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04: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==2.5.1'] in /builds/slave/test/build 18:04: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==2.5.1 18:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:45 INFO - 'HOME': '/Users/cltbld', 18:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:45 INFO - 'LOGNAME': 'cltbld', 18:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:45 INFO - 'MOZ_NO_REMOTE': '1', 18:04:45 INFO - 'NO_EM_RESTART': '1', 18:04:45 INFO - 'PAGER': '/bin/cat', 18:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:45 INFO - 'PWD': '/builds/slave/test', 18:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:45 INFO - 'SHELL': '/bin/bash', 18:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:45 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:45 INFO - 'USER': 'cltbld', 18:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:45 INFO - 'XPC_FLAGS': '0x0', 18:04:45 INFO - 'XPC_SERVICE_NAME': '0', 18:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:46 INFO - Downloading/unpacking jsonschema==2.5.1 18:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:04:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:49 INFO - Downloading functools32-3.2.3-2.tar.gz 18:04:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:04:49 INFO - warning: no files found matching '*.txt' 18:04:49 INFO - no previously-included directories found matching 'build' 18:04:49 INFO - no previously-included directories found matching 'dist' 18:04:49 INFO - no previously-included directories found matching '.git*' 18:04:49 INFO - Installing collected packages: jsonschema, functools32 18:04:49 INFO - Running setup.py install for functools32 18:04:49 INFO - warning: no files found matching '*.txt' 18:04:49 INFO - no previously-included directories found matching 'build' 18:04:49 INFO - no previously-included directories found matching 'dist' 18:04:49 INFO - no previously-included directories found matching '.git*' 18:04:49 INFO - Successfully installed jsonschema functools32 18:04:49 INFO - Cleaning up... 18:04:49 INFO - Return code: 0 18:04:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:49 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04:49 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:04:49 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 functools32==3.2.3-2 18:04:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:49 INFO - 'HOME': '/Users/cltbld', 18:04:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:49 INFO - 'LOGNAME': 'cltbld', 18:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:49 INFO - 'MOZ_NO_REMOTE': '1', 18:04:49 INFO - 'NO_EM_RESTART': '1', 18:04:49 INFO - 'PAGER': '/bin/cat', 18:04:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:49 INFO - 'PWD': '/builds/slave/test', 18:04:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:49 INFO - 'SHELL': '/bin/bash', 18:04:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:49 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:49 INFO - 'USER': 'cltbld', 18:04:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:49 INFO - 'XPC_FLAGS': '0x0', 18:04:49 INFO - 'XPC_SERVICE_NAME': '0', 18:04:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:04:49 INFO - Cleaning up... 18:04:49 INFO - Return code: 0 18:04:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:49 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04:49 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 18:04:49 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 18:04:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:49 INFO - 'HOME': '/Users/cltbld', 18:04:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:49 INFO - 'LOGNAME': 'cltbld', 18:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:49 INFO - 'MOZ_NO_REMOTE': '1', 18:04:49 INFO - 'NO_EM_RESTART': '1', 18:04:49 INFO - 'PAGER': '/bin/cat', 18:04:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:49 INFO - 'PWD': '/builds/slave/test', 18:04:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:49 INFO - 'SHELL': '/bin/bash', 18:04:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:49 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:49 INFO - 'USER': 'cltbld', 18:04:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:49 INFO - 'XPC_FLAGS': '0x0', 18:04:49 INFO - 'XPC_SERVICE_NAME': '0', 18:04:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:50 INFO - Downloading/unpacking blobuploader==1.2.4 18:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:52 INFO - Downloading blobuploader-1.2.4.tar.gz 18:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:04:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:04:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:53 INFO - Downloading docopt-0.6.1.tar.gz 18:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:04:54 INFO - Installing collected packages: blobuploader, requests, docopt 18:04:54 INFO - Running setup.py install for blobuploader 18:04:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:04:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:04:54 INFO - Running setup.py install for requests 18:04:54 INFO - Running setup.py install for docopt 18:04:54 INFO - Successfully installed blobuploader requests docopt 18:04:54 INFO - Cleaning up... 18:04:54 INFO - Return code: 0 18:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:54 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04:54 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 18:04:54 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 18:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:54 INFO - 'HOME': '/Users/cltbld', 18:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:54 INFO - 'LOGNAME': 'cltbld', 18:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:54 INFO - 'MOZ_NO_REMOTE': '1', 18:04:54 INFO - 'NO_EM_RESTART': '1', 18:04:54 INFO - 'PAGER': '/bin/cat', 18:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:54 INFO - 'PWD': '/builds/slave/test', 18:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:54 INFO - 'SHELL': '/bin/bash', 18:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:54 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:54 INFO - 'USER': 'cltbld', 18:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:54 INFO - 'XPC_FLAGS': '0x0', 18:04:54 INFO - 'XPC_SERVICE_NAME': '0', 18:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-On1uiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-NSHDiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-3KVOGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-fIJDvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-gWkJLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-J8Sr7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Ane8wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-SFAU8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:55 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-ixCCDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-jN5nIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-pCZEr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-vFvIFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-FljQp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-NeJuHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-LesBjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-GMxTo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:04:56 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-dSmu_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:04:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:04:56 INFO - Running setup.py install for manifestparser 18:04:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:04:57 INFO - Running setup.py install for mozcrash 18:04:57 INFO - Running setup.py install for mozdebug 18:04:57 INFO - Running setup.py install for mozdevice 18:04:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:04:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:04:57 INFO - Running setup.py install for mozfile 18:04:57 INFO - Running setup.py install for mozhttpd 18:04:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:04:57 INFO - Running setup.py install for mozinfo 18:04:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:04:57 INFO - Running setup.py install for mozInstall 18:04:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Running setup.py install for mozleak 18:04:58 INFO - Running setup.py install for mozlog 18:04:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Running setup.py install for moznetwork 18:04:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Running setup.py install for mozprocess 18:04:58 INFO - Running setup.py install for mozprofile 18:04:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:04:58 INFO - Running setup.py install for mozrunner 18:04:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:04:59 INFO - Running setup.py install for mozscreenshot 18:04:59 INFO - Running setup.py install for moztest 18:04:59 INFO - Running setup.py install for mozversion 18:04:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:04:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:04:59 INFO - Cleaning up... 18:04:59 INFO - Return code: 0 18:04:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:04:59 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 18:04:59 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 18:04:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:04:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:59 INFO - 'HOME': '/Users/cltbld', 18:04:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:59 INFO - 'LOGNAME': 'cltbld', 18:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:59 INFO - 'MOZ_NO_REMOTE': '1', 18:04:59 INFO - 'NO_EM_RESTART': '1', 18:04:59 INFO - 'PAGER': '/bin/cat', 18:04:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:59 INFO - 'PWD': '/builds/slave/test', 18:04:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:59 INFO - 'SHELL': '/bin/bash', 18:04:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:04:59 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:04:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:59 INFO - 'USER': 'cltbld', 18:04:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:59 INFO - 'XPC_FLAGS': '0x0', 18:04:59 INFO - 'XPC_SERVICE_NAME': '0', 18:04:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:59 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-GbcmN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:59 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)) 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:59 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-wrV9Rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-AwlVwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:00 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-6xbs0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-MGkaFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:00 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-RSs5kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:00 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-gPEf_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:00 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-z4KTrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:00 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-U9R1j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:00 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-inecwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-MwCp9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:00 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)) 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:00 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-qNAErD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:01 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Id8Ou3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:01 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)) 18:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:01 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Isw_dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 18:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:01 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Mk6XzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:01 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)) 18:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:01 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-j7sast-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 18:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:01 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-kOMLrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:01 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)) 18:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:05:01 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)) 18:05:01 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:05:01 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:05:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:04 INFO - Downloading blessings-1.6.tar.gz 18:05:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:04 INFO - Installing collected packages: blessings 18:05:04 INFO - Running setup.py install for blessings 18:05:04 INFO - Successfully installed blessings 18:05:04 INFO - Cleaning up... 18:05:04 INFO - Return code: 0 18:05:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:04 INFO - Reading from file tmpfile_stdout 18:05:04 INFO - Current package versions: 18:05:04 INFO - blessings == 1.6 18:05:04 INFO - blobuploader == 1.2.4 18:05:04 INFO - docopt == 0.6.1 18:05:04 INFO - functools32 == 3.2.3-2 18:05:04 INFO - jsonschema == 2.5.1 18:05:04 INFO - manifestparser == 1.1 18:05:04 INFO - mozInstall == 1.12 18:05:04 INFO - mozcrash == 1.0 18:05:04 INFO - mozdebug == 0.1 18:05:04 INFO - mozdevice == 0.49 18:05:04 INFO - mozfile == 1.2 18:05:04 INFO - mozhttpd == 0.7 18:05:04 INFO - mozinfo == 0.9 18:05:04 INFO - mozleak == 0.1 18:05:04 INFO - mozlog == 3.4 18:05:04 INFO - moznetwork == 0.27 18:05:04 INFO - mozprocess == 0.25 18:05:04 INFO - mozprofile == 0.28 18:05:04 INFO - mozrunner == 6.13 18:05:04 INFO - mozscreenshot == 0.1 18:05:04 INFO - mozsystemmonitor == 0.3 18:05:04 INFO - moztest == 0.8 18:05:04 INFO - mozversion == 1.4 18:05:04 INFO - psutil == 3.1.1 18:05:04 INFO - requests == 1.2.3 18:05:04 INFO - wsgiref == 0.1.2 18:05:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:04 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:05:04 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 18:05:04 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 18:05:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:05:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:04 INFO - 'HOME': '/Users/cltbld', 18:05:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:04 INFO - 'LOGNAME': 'cltbld', 18:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:04 INFO - 'MOZ_NO_REMOTE': '1', 18:05:04 INFO - 'NO_EM_RESTART': '1', 18:05:04 INFO - 'PAGER': '/bin/cat', 18:05:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:04 INFO - 'PWD': '/builds/slave/test', 18:05:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:04 INFO - 'SHELL': '/bin/bash', 18:05:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:05:04 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:05:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:04 INFO - 'USER': 'cltbld', 18:05:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:04 INFO - 'XPC_FLAGS': '0x0', 18:05:04 INFO - 'XPC_SERVICE_NAME': '0', 18:05:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 INFO - Cleaning up... 18:05:05 INFO - Return code: 0 18:05:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 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 0x105341b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105344768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb2dba870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, '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.3dDRbDki4r/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.NEYyZwBvZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 18:05:05 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 18:05:05 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 18:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:05 INFO - 'HOME': '/Users/cltbld', 18:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:05 INFO - 'LOGNAME': 'cltbld', 18:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:05 INFO - 'MOZ_NO_REMOTE': '1', 18:05:05 INFO - 'NO_EM_RESTART': '1', 18:05:05 INFO - 'PAGER': '/bin/cat', 18:05:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:05 INFO - 'PWD': '/builds/slave/test', 18:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:05 INFO - 'SHELL': '/bin/bash', 18:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:05:05 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:05 INFO - 'USER': 'cltbld', 18:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:05 INFO - 'XPC_FLAGS': '0x0', 18:05:05 INFO - 'XPC_SERVICE_NAME': '0', 18:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:05:05 INFO - Cleaning up... 18:05:05 INFO - Return code: 0 18:05:05 INFO - Running post-action listener: _resource_record_post_action 18:05:05 INFO - Running post-action listener: _start_resource_monitoring 18:05:05 INFO - Starting resource monitoring. 18:05:05 INFO - [mozharness: 2017-03-15 01:05:05.405065Z] Finished create-virtualenv step (success) 18:05:05 INFO - [mozharness: 2017-03-15 01:05:05.406048Z] Running install step. 18:05:05 INFO - Running pre-action listener: _resource_record_pre_action 18:05:05 INFO - Running main action method: install 18:05:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:05 INFO - Reading from file tmpfile_stdout 18:05:05 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:05 INFO - Reading from file tmpfile_stdout 18:05:05 INFO - Output received: 18:05:05 INFO - Usage: mozinstall [options] installer 18:05:05 INFO - Options: 18:05:05 INFO - -h, --help show this help message and exit 18:05:05 INFO - -d DEST, --destination=DEST 18:05:05 INFO - Directory to install application into. [default: 18:05:05 INFO - "/builds/slave/test"] 18:05:05 INFO - --app=APP Application being installed. [default: firefox] 18:05:05 INFO - mkdir: /builds/slave/test/build/application 18:05:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:05:26 INFO - Reading from file tmpfile_stdout 18:05:26 INFO - Output received: 18:05:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:05:26 INFO - Running post-action listener: _resource_record_post_action 18:05:26 INFO - [mozharness: 2017-03-15 01:05:26.499706Z] Finished install step (success) 18:05:26 INFO - [mozharness: 2017-03-15 01:05:26.499836Z] Running run-tests step. 18:05:26 INFO - Running pre-action listener: _resource_record_pre_action 18:05:26 INFO - Running main action method: run_tests 18:05:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:26 INFO - 2017-03-14 18:05:26.514 screenresolution[1899:13115] starting screenresolution argv=screenresolution get 18:05:26 INFO - 2017-03-14 18:05:26.517 screenresolution[1899:13115] Display 0: 1600x1200x32@60 18:05:26 INFO - 2017-03-14 18:05:26.523 screenresolution[1900:13117] starting screenresolution argv=screenresolution list 18:05:26 INFO - Available Modes on Display 0 18:05:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:26 INFO - Intel Iris: 18:05:26 INFO - Chipset Model: Intel Iris 18:05:26 INFO - Type: GPU 18:05:26 INFO - Bus: Built-In 18:05:26 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:26 INFO - Vendor: Intel (0x8086) 18:05:26 INFO - Device ID: 0x0a2e 18:05:26 INFO - Revision ID: 0x0009 18:05:26 INFO - Displays: 18:05:26 INFO - Display: 18:05:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:26 INFO - Display Serial Number: 1600x1200 60 18:05:26 INFO - Main Display: Yes 18:05:26 INFO - Mirror: Off 18:05:26 INFO - Online: Yes 18:05:26 INFO - Rotation: Supported 18:05:26 INFO - Return code: 0 18:05:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:26 INFO - Python 2.7.3 18:05:26 INFO - Return code: 0 18:05:26 INFO - grabbing minidump binary from tooltool 18:05:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:26 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 0x10511fd30>, '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 0x105046db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105114be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:26 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 18:05:26 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 18:05:26 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 18:05:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:26 INFO - Return code: 0 18:05:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:05:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:26 INFO - ENV: RUST_BACKTRACE is now 1 18:05:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:05:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0264 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3dDRbDki4r/Render', 18:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:26 INFO - 'HOME': '/Users/cltbld', 18:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:26 INFO - 'LOGNAME': 'cltbld', 18:05:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:26 INFO - 'MOZ_NO_REMOTE': '1', 18:05:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:26 INFO - 'NO_EM_RESTART': '1', 18:05:26 INFO - 'PAGER': '/bin/cat', 18:05:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:26 INFO - 'PWD': '/builds/slave/test', 18:05:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:26 INFO - 'RUST_BACKTRACE': '1', 18:05:26 INFO - 'SHELL': '/bin/bash', 18:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEYyZwBvZW/Listeners', 18:05:26 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 18:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:26 INFO - 'USER': 'cltbld', 18:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:26 INFO - 'XPC_FLAGS': '0x0', 18:05:26 INFO - 'XPC_SERVICE_NAME': '0', 18:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:05:27 INFO - mozversion application_buildid: 20170314164336 18:05:27 INFO - mozversion application_changeset: fd8bdabb4813164e70b51d02b420e3659eb38536 18:05:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:05:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:27 INFO - mozversion application_name: Firefox 18:05:27 INFO - mozversion application_remotingname: firefox-dev 18:05:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:05:27 INFO - mozversion application_vendor: Mozilla 18:05:27 INFO - mozversion application_version: 54.0a2 18:05:27 INFO - mozversion platform_buildid: 20170314164336 18:05:27 INFO - mozversion platform_changeset: fd8bdabb4813164e70b51d02b420e3659eb38536 18:05:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:05:27 INFO - mozversion platform_version: 54.0a2 18:05:27 INFO - using testdate: 1489539927 18:05:27 INFO - actual date: 1489539927 18:05:27 INFO - starting webserver on 'localhost:51550' 18:05:27 INFO - SUITE-START | Running 2 tests 18:05:27 INFO - TEST-START | dromaeo_css 18:05:27 INFO - operating with platform_type : mac_ 18:05:27 INFO - Initialising browser for dromaeo_css test... 18:05:27 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpGHZMUU/profile http://localhost:51550/getInfo.html) 18:05:29 INFO - PROCESS | 1910 | 2017-03-14 18:05:29.796 plugin-container[1912:13289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 18:05:29 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:29 INFO - PROCESS | 1910 | 2017-03-14 18:05:29.801 plugin-container[1912:13289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 18:05:29 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:29 INFO - PROCESS | 1910 | 2017-03-14 18:05:29.801 plugin-container[1912:13289] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:05:29 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 18:05:29 INFO - PROCESS | 1910 | colorDepth:24 18:05:29 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 18:05:29 INFO - PROCESS | 1910 | __metrics 18:05:29 ERROR - PROCESS | 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 18:05:30 INFO - TEST-INFO | 1910: exit 0 18:05:30 INFO - Browser initialized. 18:05:30 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:30 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpGHZMUU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:33 INFO - PROCESS | 1913 | 2017-03-14 18:05:33.127 plugin-container[1914:13418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 18:05:33 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:33 INFO - PROCESS | 1913 | 2017-03-14 18:05:33.127 plugin-container[1914:13418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 18:05:33 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:33 INFO - PROCESS | 1913 | 2017-03-14 18:05:33.127 plugin-container[1914:13418] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:05:42 INFO - PROCESS | 1913 | RSS: Main: 220114944 18:05:42 INFO - PROCESS | 1913 | 18:08:46 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51550/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51550/tests/dromaeo/cssquery-ext.html) 18:08:48 INFO - PROCESS | 1913 | RSS: Main: 241405952 18:08:48 INFO - PROCESS | 1913 | 18:10:31 INFO - PROCESS | 1913 | ************************* 18:10:31 INFO - PROCESS | 1913 | A coding exception was thrown and uncaught in a Task. 18:10:31 INFO - PROCESS | 1913 | 18:10:31 ERROR - PROCESS | 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:10:31 INFO - PROCESS | 1913 | Full stack: 18:10:31 INFO - PROCESS | 1913 | ************************* 18:11:50 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51550/tests/dromaeo/cssquery-ext.html (next: http://localhost:51550/tests/dromaeo/cssquery-jquery.html) 18:11:51 INFO - PROCESS | 1913 | RSS: Main: 251011072 18:11:51 INFO - PROCESS | 1913 | 18:14:53 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51550/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51550/tests/dromaeo/cssquery-mootools.html) 18:14:54 INFO - PROCESS | 1913 | RSS: Main: 259670016 18:14:54 INFO - PROCESS | 1913 | 18:17:55 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51550/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51550/tests/dromaeo/cssquery-prototype.html) 18:17:56 INFO - PROCESS | 1913 | RSS: Main: 256425984 18:17:56 INFO - PROCESS | 1913 | 18:20:59 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51550/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51550/tests/dromaeo/cssquery-yui.html) 18:20:59 INFO - PROCESS | 1913 | RSS: Main: 262422528 18:20:59 INFO - PROCESS | 1913 | 18:24:02 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51550/tests/dromaeo/cssquery-yui.html (next: http://localhost:51550/tests/dromaeo/cssquery-dojo.html) 18:24:03 INFO - PROCESS | 1913 | RSS: Main: 258838528 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | __start_tp_report 18:24:03 INFO - PROCESS | 1913 | _x_x_mozilla_page_load 18:24:03 INFO - PROCESS | 1913 | _x_x_mozilla_page_load_details 18:24:03 INFO - PROCESS | 1913 | |i|pagename|runs| 18:24:03 INFO - PROCESS | 1913 | |0;dojo.html;3502.0019294501058;3537.204129070959;3545.663161999609;3549.893503194905;3572.8570857165714;6091.177691011712;6144.508439324854;6156;6166.7533298667995;6184.381561843813;6098.14625952367;6152.138700581911;6175.505959523239;6180.1656776335185;6200.720967556459;5470.7264636768205;5561.221428999943;5575.275214222166;5586.581006424514;5647.237622920894;5640.858978525549;5643.068893632546;5651.9717401413145;5658.321001479828;5697.344805347397;5139.074966506046;5166.741662916839;5356.625036247464;5389.51494365507;5646.661200327973;5466.398696143421;5478.945210547883;5485.040117979377;5533.501984821345;5552.361478429992;5501.092319767234;5562.082256427685;5581.804636837732;5601.915971260434;5608.551315894719;6171.814845554641;6227.782027629057;6233.407826256498;6241.407066328691;6285.2457705075685;6275.529335299825;6293.527985401113;6373.139626150457;6541.9345806541805;6553.6395498247575;6510.967445162792;6595.967020164869;6599.406053455212;6601.306862779387;6602.867942641168;6166.907496387557;6206.782762603287;6261.9373806262265;6276.717547710409;6277.968610157011;6467.4179323860135;6476.805695829133;6621.602703837785;6749.426298764562;6772.72909083641;6047.5464340173785;6314.526410519229;6381.617102973743;6386.584871983307;6404.935950640527;6509.121268628769;6509.1863517060365;6525.804225873231;6532.510061745388;6540.149780528596;6379.617222966637;6388.936110638927;6412.102305677209;6445;6453.45145662624;5150.4334523202415;5305.893882122413;6304.306526282106;6309.968450157719;6389.3291204423795;4804.663673542818;5073.34046573943;5142.254373115878;5203.7918483261;5272.947270527322;4345.630621397154;4454.331850222427;4621.353010578522;4646.512116227815;4662.020975595163;6218.751249950042;6268.4671802897265;6269.122322874801;6276.278228003746;6299.118123462719;5504.311961004874;6318.652474113968;6649.3018233085795;6652.135222421054;6677.098591690156;6108.251008765781;6117.694115294217;6126.3955342902;6140.534923917717;6146.5390095743;5839.328477225088;5866.7653293868625;5876.265466816648;5880.294364676266;5903.35063143054;6830.21452532965;6840.452763778886;6841.589504629738;6867.175938887365;6871.5189936703955;6261.186045814197;6381.617102973836;6497.122888410193;6501.2523559791225;6553.5084868634085;6000.609960352563;6843.110395648733;6853.7943861684225;6859.725610975505;6865.27914568983;6490.772822951174;6504;6512.967435162794;6518;6520.902186467112;6278.905816412849;6296.370362963667;6324.304326523987;6437.742490300336;6569.671516424255;5693.800716974886;6076.57463977535;6336.968315158395;6370.904436433365;6381.298057213796;6330.49356051524;6369.20384951881;6369.235691717024;6378.1070650108095;6389.073584330339;4899.92201715622;5823.18475413451;6127.877442451215;6146.5248340398875;6151.108089327112;6279.215098112736;6290.748370065146;6383.616982981036;6401.231852177582;6402.519811014099;6265.404786545271;6278.937210627835;6287.056941458818;6296.0555916612875;6301.6219026858535 18:24:03 INFO - PROCESS | 1913 | |1;ext.html;738.7414404958265;765.6095391350411;811.6834434570517;954.1937063181609;956.2445667922342;35546.64453355466;36564.81717591412;37411.064723381925;37496.81251593742;37574.4363834542;36707;36881.81559092201;36944;36961.63038369615;37041;7704.383649308056;10254.641087561937;10446.320989135682;10617.57529698811;10671.83992240117;10346.948265258663;10391.428471434068;10461.581536738522;10464;10470.16238700902;10442.00800923911;10449.164066874631;10462.163026957825;10466.848646648863;10470.581176752921;10392.584296628163;10401.115905148034;10409.375437473776;10444.895551044507;10448.947755261175;10234.181265498743;10282.48587570626;10290.897091029068;10301.793964120676;10305.639302624448;31696.524552131737;31733.682663173302;31867.203319916956;32012.679873201203;32086.679133208603;3665.6334366563397;3691.575468821093;3730.1607138393915;3756.530433695788;3770.7549009314307;19777;24037.639435408484;24329.27012189637;25160.370990725194;25288.620670689954;2653.4162484253625;3073.8155710657206;3156.636986746508;3264.167637252509;3272.934541309184;321933;324741;368791.1560442207;400887;407424.9628751867;41949.53176638864;41986.79006604947;42122;42158.78920605377;42180.523681670995;32688.509672354932;35690.821545892104;37371.31829067699;43420.69737907868;43449;10963.50664220112;13112.213267203999;13141.54004609834;13177.538786142439;13242.337883105805;10391.324563903323;11133.443327833576;11459.369734664508;11683.76632467346;11715.589954351728;11555.88444115548;11563.479643416067;11586.073114150848;11615.477303521362;11665.59170429031;5590.496855283044;5635.718214089279;5673.517750991129;5679.261695979496;5685.630434021776;777.8483195776769;917.6467060181744;933.169479163545;942.189716843514;977.2231076294363;26297.342566435993;27300.180994570193;27305;27574.724252757616;27615.723842761316;928.8003079337975;990.8166989107038;1042.421456091861;1064.531606093332;1095.9697883989024;12910.483580656857;14420.351084201237;14885.851141488447;15115.31981060855;15348.539543813704;418761.9061904671;419743;421169;423030;427942;53095;53190.404287871424;53309.46690533045;53315.46684533105;53319.20021199685;1388.569543441516;1422.1467119728081;1433.383645032625;1436.1167881752647;1441.992790036064;27678;28411.289717757223;28470.288242794097;28511.42977140487;28825.135245942656;40501.1899762009;40586.391204131374;40602.593974059884;40619;40733.59266407298;375911;446462;451199;452375;453386;30477.390452191277;30953.845230773702;31068.91258805931;31096.844515777277;31226.843865780527;36548;39384;41186;42877.78561107175;42891.35662965121;30729.846350768996;32340;33609.15977100494;35264.589416423056;35661;28787.568186477638;34404.655953440146;35301;35609.46585801267;35618.64381356257;34167.65832341744;34857.825710871286;35451;35461.645383546864;35651;25188.622170667088;31202.687973119977;31220;31255.53116703206;31379.215519612193;20252.79747202509;20504.487387815425;23840.880795595913;24210.87894560516;24267 18:24:03 INFO - PROCESS | 1913 | |2;jquery.html;377.43384922616076;412.73997381649576;413.65666496807654;420.9663226941844;453.03955614098095;19810.40568782939;20137.899310503464;21272.89363553184;21417.678734818972;21489.355319340404;21743.45641358967;21990.99999999996;22099.779002209973;22710;22738.54522909541;6550.246721627026;6735.158105236846;6787.660616969157;6819.318068193191;6842;6527.249366322886;6640.169978752656;6729.7644582439625;6791.728330866759;6794.728210871559;6531.216254049521;6710.563813352116;6755.256921738604;6779.864402711943;6779.966100169517;6620.867582648344;6703.296153903843;6758.8310292242595;6763.526553141282;6770.796876093725;6585.934140658628;6667.833304167386;6973.721051157948;7126.3942564882045;7146.178189508221;20457.693134602992;25940;26630.20109396721;26650.46699066008;26910.73089269102;3129.5618613394145;3231.4829627259523;3259.1200375898375;3296.258341873059;3324.2852786650988;15109.47116850905;15919.681606367923;16273.593160170973;16374.181290935525;17132.571685707833;2056.7737548869613;2059.4542446251753;2063.2778527515397;2077.1172251793078;2138.4653836540865;166181;174162.1291893545;176255;177033.99999999872;177312;18349.330133973257;19607.117679704446;20851;20901.01440363163;21296.467588310108;32437.675623243937;32755.836220818743;32997.17507062294;33058.00825975225;33154;9128.58921348541;9266.721998340061;10389.324693894872;10565.418901960313;10696.465176741132;7062.293770622897;8802.60388282516;8807.955960220157;8822.779430514289;8955.59699813497;7539.736109236151;9746.832279509188;9784.461854597923;9893.060159284856;9896.851547226797;3915.314819906505;4045.1505183911117;4045.7458187961824;4047.7166598337835;4057;721.585088574072;846.2172490446312;859.6002858670623;868.9217970382696;922.2115091596722;21085.472863178544;21550;21567.56864862694;21568.784312156993;21648.675269870964;912.9452232866049;924.4822899176348;929.8279818233649;937.1846493550654;944.1455482788087;8737.825243495094;10383.740406489898;10657.561229233954;10695.94652026735;11112.499937502831;707110;721617;731319.3736125354;760228;767123;119259;156888;157090;163079;168832.15583922248;988.2538683294067;1100.763335882773;1191.8688944216128;1206.6621346023128;1281.2504684759333;16198.29917858608;16514.75227871559;19650.606987859876;20702.792972070085;20707.79292207059;67033.66483167748;67905;68322;68347.3165268341;71073;73165;82682.17317826985;92436.68908277348;92816.53591731998;94467.52766236124;9010.954945225232;11083.889161108607;11663.475143618727;11865.991390731884;12036.75926481483;13313.733725325246;13348.466061357436;13661.590152295446;13789.793153102511;13791;10719.89280107189;11185.888141118485;11421;11442.94278528602;11639.6508104757;11268.042216411697;11405.144614154138;11495.252808567417;11790.174687771774;11797.410129493663;8895.955520222358;11509.13681473876;11795.469203886018;11870.347130907883;12024;9272.490013049348;11725.530978760755;12015.459304331502;12065.939670301592;12619.558315459282;8398.916010839814;8812.647494100163;9691.12779849817;10033.996600339908;11633.767324653629 18:24:03 INFO - PROCESS | 1913 | |3;mootools.html;1532.923353832308;1542.7377345851198;1559.0411896683536;1571.8742500599953;1580.4863419388698;10774.5690172393;11047.668569942894;11374.0332071774;11492.999999999989;11520.596779112733;11206.383648899327;11355.659330220085;11381.089512838973;11409.31544107354;11454.599089031886;2374.2995816234165;2427.8421902576274;2431.0761910473993;2431.5623187826186;2434.5982912819363;2359.528094381122;2398.796102331304;2403.663487111797;2408.7952524035477;2410.7227668818045;2375.4655202579456;2399.748026457223;2404.903803847844;2409.3253888911045;2412.9879350603223;2361.0791791201445;2370.0282884017556;2385.6421536769535;2386.0455817672896;2386.904523819045;2007.6888082347114;2349.835511514195;2353.658719485682;2405.025964484387;2406.8796560172095;9519.714408567685;10034.595156677999;11385.9183377579;11509.251898626646;11537.653870383818;4900.607951363918;4995.000999800054;5728.627639203439;5882.47057764799;6020.548458865603;3571.4642803579673;3602.441621548664;3666.5416822897137;3686.225892562565;3716.9964109690504;1053.5101177952213;1160.9883901160965;1166.5742004168503;1168.6793922867191;1227.9140460167791;13676.042677012612;13814;14762.48331308399;14959.850401496064;15202.543923682308;4659.487456379796;4767.165745994437;4781.904361912742;4789.377380940456;4793.089313030513;11705.005074568588;11763.235389699643;11802.40987950599;11818.995385392165;11821.88178118225;5689.544836413077;5746.281714785652;5780.826575202751;5787.160861675117;5789.305283366023;4306.82772689089;5218.556422704037;5355.758990845421;5363.410024897258;5377.462253774591;3808.4287356896134;3996.740211886276;4003.7197396181987;4065.756054636672;4079.836806527765;883.9646414143491;896.7668406214431;1041.073444634276;1049.884512703602;1057.9576816927238;930.2000279759402;933.1601558597181;936.194872409727;948.8529277961927;991.6628346362271;6390.201224846895;6459.709313080923;6688.85291119509;6832.965835170892;6883.483738719516;946.4747065378643;969.4958621516772;976.0873583199731;982.9017098290257;1021.7598864266891;4228.450301460852;4306.246406878847;4314.460692413449;4434.756088415168;4463.910721785546;13748.450061997608;14248.643783905278;14254.216018118896;14263.144211347353;14298.928505357615;13528.797068043987;13681.452741890213;14041.438342466392;14087.929560352337;14232.501862434972;10137.898621013843;10270.691879243635;10361.378317300987;10370.27408081427;10399.94800025995;6560.44236239925;6572.244191917799;6600.009998500072;6670.299618540177;6690;12341.814872776737;12349.444275007467;12450.875491244971;12488.25070495773;12490.437930293341;11109.722256943318;12593.93703031479;12692.17500862478;13023.413946372246;13065.346732663518;8984.505852178192;9204.907950920673;9473.384230025302;9493.382930109521;9505.619775208914;9911.900880991097;10200;10316.17470602335;10357.533910974174;10427.635032773816;9143.3599648024;9189.494577798285;9245.537723113683;9366.95316523413;9497.287703422133;7476.364509016795;8756.912430875609;8922.107789221025;8935.017148113833;9087.954560227157;7881.369490440637;8030.558319292495;8102.83794324122;8103.31121854649;8118.188181181835;5838.416158384298;5844.620099693507;5904.704764761831;6079.939200607937;6139.079138129317;5991.820245392645;6042.909356359746;6094.481969032682;6161.753529858755;6257.874842503215 18:24:03 INFO - PROCESS | 1913 | |4;prototype.html;305.04672897196264;333.0723933844244;345.4472843450481;383.28899890702746;391.7845185148168;4638.512956139603;4751.552950230625;4836.766624510745;5067.442581316052;5510.283663123793;4461.553844615537;4921.532454416833;5251.529571719915;5366.91949620755;5395.433479484656;3952.6245006724316;4030.3349947258666;4432.578905004019;4586.197415452282;4683.7189768613825;3933.6656384207377;4395.538468460813;4652.767361631922;4671.481768425276;4738.9526104739025;3831.731778775487;4144.5855414458465;4551.522090180533;4612.654050946176;4644.790984405693;3861.7876016819064;3932.6460618544324;4272.807723652443;4719.144917325038;4728.855311218036;3587.4080776671817;4048.9472737088277;4420.403245561873;4475.283954567253;4494.9325760113625;4550.270897059129;5271.472852714698;5279.445658205871;5304.814331498379;5313.38896026958;4616.130467160805;4797.9520204797855;5175.068487672217;5428.5928555358505;5451.182322651594;5094.79620815167;5340.62615616907;5769.04810706233;5772.977509566662;5785.6528608283215;4210.873673789761;4220.556841531657;4510.503844577094;4522.909541809146;4603.861884143481;5109.693418394871;5474.753636086386;6108.358622344635;6114.798672477905;6130.509559235251;4553.840615578439;4697.718136911796;4715.434147902205;4724.551167639069;4725.5510726480925;4227.852025179104;4239.576042395735;4695.929561056587;4705.282571861246;4804.876830060489;4500.572445617661;4999.400071991385;5112.284280200774;5145.816462213637;5155.74221288934;4687.086018226481;5003.524665156804;5313.521783039545;5689.317281926195;5778.306603207558;1441.3153751967732;1513.7805018272288;1606.7188242057593;1607.100023986548;1625.520471460922;1051.033049594375;1085.733995171188;1156.7166044319197;1202.903767698582;1224.4306397525193;4981.95378970413;5147.742612869341;5264.341957255343;5356.4107948125675;5361.809723214681;5137.948620513822;5482.945170548323;5817.069268916953;5940.247626949974;5993.820185394445;4815.662903596714;4867.561919427269;5130.692158470429;5360.373417025629;5403.378611459654;2704.35095577064;3087.4133914556164;3095.1488340706037;3102.968970310268;3116.9532457013165;5134.762542206928;5605.411431799684;5759.971200144057;6121.938780612226;6231.912309376319;4872;4944.24479309847;5661.745221465044;5787.4729633200195;5818.963362823063;5515.558755299567;5578.079616863172;6150.938490615036;6249.250089989139;6273.686315684197;4974.751262436791;5133.948660513347;5283.656562323436;5754.4533269339345;5917.230760001173;4935.926910697443;4993.880048959551;5540.418256083214;5562.944370556243;5908.497777688773;5067.822626208065;5502.6433875335215;5577.832665020056;5781.248437703071;6190.907136392868;4194.958050419579;4386.320120381346;4509.07563949383;4648.511906249931;4650.767461626972;5157;5348.679079255257;5544.892940682515;5756.963746525666;5927;4673.556012178974;5152.5877929766275;5189.662671926313;5779.306483222041;5783.062674004255;4937.703737775601;5145.948540514547;5326.7336633167415;5419.295491586068;5745.432381256827;4711.667724476406;5026.0199261143725;5053.974730126326;5516.503514683698;5621.269234999424;4812.614990800657;4836.564709176191;5229.9738501308775;5694.686792226467;5724.255846739898;4853.344798452305;5202.73986300691;5525.9723701382845;5579.553635709215;5611.8877622448135 18:24:03 INFO - PROCESS | 1913 | |5;yui.html;674.9493787965903;702.7118881258685;712.8859382498802;797.7845981584973;818.9099199088101;14748.262586870655;15886.285117169726;16047.277872495752;16100.516984490454;16148.03111813292;14498.927505362459;15252.923735381335;15375.769363459556;15391.461298854514;15410.229488525556;867.1198733285704;950.5437390052745;950.6910254167389;951.6764300137952;952.4951775558965;891.7547674389563;925.3152667026386;930.525432029663;944.9622015119387;946.186279799372;847.631280393031;991.4447909170888;994.2344394815988;994.7363948553639;995.7610173558367;980.2060331131806;981.5141504955093;991.5587563534231;991.6777047459568;993.9910990344806;728.6466063958976;729.854029194158;730.3135053050116;732.2128711634961;732.4762794601885;13807.309634518335;15664.686706265924;15907.363705451768;16071.839281607268;16378.918105409515;13993.72012559743;14313.642158946006;14389.280535973265;14396.568102956928;14489.347979340955;11580;11637.650870473899;11836.704082397922;11848.585299514476;11899.881001189964;3619.438986957026;3644.1618427761764;3655.5796083450477;3763.9059023524355;3833.4058220975794;79300.60349698215;104999;112851;115387.99999999916;116054.67890642224;10550.683479495474;10643.25497215203;10655.733606659896;10867.076298514714;11127.387993660426;22404.43988900258;22426.55146897053;22561.871906404944;22784.772152278267;22802.201922932618;7373.483856130019;7570.5836179010685;7646.541207527566;7686.923130768732;7892;4204.285271503791;4234.301340278819;4319.913601727948;4361.803718832597;4506.7070640408265;3429.3998550253605;3439.1230236290085;3498.440249560109;3508.08789714676;3514.332276867387;511.3863363963259;523.7250443517185;527.1854984049653;527.7097596322084;529.7457220534088;627.9529035322369;635.6726285962733;653.3368630839701;662.2847324889108;704.5807744392079;9581.952090239503;9602.759931001641;9876.456794876207;10387.94806025965;10669.466526673636;553.9202036676094;558.8910162518351;592.570386469806;635.5995129050509;700.8903201363829;4756.857294281177;5079.6698214615935;5083.72039537829;5084.9745751271;5087.694738315713;101293;101865;103466;104644.47677761562;106455;90569.54715226514;92698;93864.53067734618;95821.52089239508;96188;69153;70926.87219383426;72636.63681681664;79367.60316198382;81057;10136.44249566281;10889.891101089204;11098.944505277423;11377.54489820431;11453.656390308304;47195.29207061965;47963;48716.512834871195;48874.511254886995;51184.48815511797;63471.36528634655;63628.04557931727;65066.34933650792;65521.67239163774;65846.67076664585;12147.817782733442;12175.269483830998;12279.324637145042;12552.309622970824;12955.676108097374;14349.784753228918;14466.927665361605;14472.986890917535;14609.707805844037;15150.393984240507;11281.887181128084;12899.935500322814;13497.595072147458;13543.187408755506;13719;12903.832914827803;13252.867471325164;13444.327783610976;13670.17978921268;13764.633089964113;12362.196457230611;13050.804237936249;13337.53318633843;13433.596992090253;13605.591832245049;10895.618653347095;11078.45084409136;11228.382438965935;11356.602518911799;11625.418729063344;10431.530581123716;10662.946685266525;11249.1563132767;11307.321560706385;11310.660680179608 18:24:03 INFO - PROCESS | 1913 | __end_tp_report 18:24:03 INFO - PROCESS | 1913 | __start_cc_report 18:24:03 INFO - PROCESS | 1913 | _x_x_mozilla_cycle_collect,422 18:24:03 INFO - PROCESS | 1913 | __end_cc_report 18:24:03 INFO - PROCESS | 1913 | __startTimestamp1489541043131__endTimestamp 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | ------- Summary: start ------- 18:24:03 INFO - PROCESS | 1913 | Number of tests: 6 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | [#0] dojo.html Cycles:165 Average:6025.79 Median:6268.47 stddev:681.15 (10.9%) stddev-sans-first:653.84 18:24:03 INFO - PROCESS | 1913 | Values: 3502.0 3537.2 3545.7 3549.9 3572.9 6091.2 6144.5 6156.0 6166.8 6184.4 6098.1 6152.1 6175.5 6180.2 6200.7 5470.7 5561.2 5575.3 5586.6 5647.2 5640.9 5643.1 5652.0 5658.3 5697.3 5139.1 5166.7 5356.6 5389.5 5646.7 5466.4 5478.9 5485.0 5533.5 5552.4 5501.1 5562.1 5581.8 5601.9 5608.6 6171.8 6227.8 6233.4 6241.4 6285.2 6275.5 6293.5 6373.1 6541.9 6553.6 6511.0 6596.0 6599.4 6601.3 6602.9 6166.9 6206.8 6261.9 6276.7 6278.0 6467.4 6476.8 6621.6 6749.4 6772.7 6047.5 6314.5 6381.6 6386.6 6404.9 6509.1 6509.2 6525.8 6532.5 6540.1 6379.6 6388.9 6412.1 6445.0 6453.5 5150.4 5305.9 6304.3 6310.0 6389.3 4804.7 5073.3 5142.3 5203.8 5272.9 4345.6 4454.3 4621.4 4646.5 4662.0 6218.8 6268.5 6269.1 6276.3 6299.1 5504.3 6318.7 6649.3 6652.1 6677.1 6108.3 6117.7 6126.4 6140.5 6146.5 5839.3 5866.8 5876.3 5880.3 5903.4 6830.2 6840.5 6841.6 6867.2 6871.5 6261.2 6381.6 6497.1 6501.3 6553.5 6000.6 6843.1 6853.8 6859.7 6865.3 6490.8 6504.0 6513.0 6518.0 6520.9 6278.9 6296.4 6324.3 6437.7 6569.7 5693.8 6076.6 6337.0 6370.9 6381.3 6330.5 6369.2 6369.2 6378.1 6389.1 4899.9 5823.2 6127.9 6146.5 6151.1 6279.2 6290.7 6383.6 6401.2 6402.5 6265.4 6278.9 6287.1 6296.1 6301.6 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | [#1] ext.html Cycles:180 Average:53537.45 Median:32049.68 stddev:108656.16 (339.0%) stddev-sans-first:108888.65 18:24:03 INFO - PROCESS | 1913 | Values: 738.7 765.6 811.7 954.2 956.2 35546.6 36564.8 37411.1 37496.8 37574.4 36707.0 36881.8 36944.0 36961.6 37041.0 7704.4 10254.6 10446.3 10617.6 10671.8 10346.9 10391.4 10461.6 10464.0 10470.2 10442.0 10449.2 10462.2 10466.8 10470.6 10392.6 10401.1 10409.4 10444.9 10448.9 10234.2 10282.5 10290.9 10301.8 10305.6 31696.5 31733.7 31867.2 32012.7 32086.7 3665.6 3691.6 3730.2 3756.5 3770.8 19777.0 24037.6 24329.3 25160.4 25288.6 2653.4 3073.8 3156.6 3264.2 3272.9 321933.0 324741.0 368791.2 400887.0 407425.0 41949.5 41986.8 42122.0 42158.8 42180.5 32688.5 35690.8 37371.3 43420.7 43449.0 10963.5 13112.2 13141.5 13177.5 13242.3 10391.3 11133.4 11459.4 11683.8 11715.6 11555.9 11563.5 11586.1 11615.5 11665.6 5590.5 5635.7 5673.5 5679.3 5685.6 777.8 917.6 933.2 942.2 977.2 26297.3 27300.2 27305.0 27574.7 27615.7 928.8 990.8 1042.4 1064.5 1096.0 12910.5 14420.4 14885.9 15115.3 15348.5 418761.9 419743.0 421169.0 423030.0 427942.0 53095.0 53190.4 53309.5 53315.5 53319.2 1388.6 1422.1 1433.4 1436.1 1442.0 27678.0 28411.3 28470.3 28511.4 28825.1 40501.2 40586.4 40602.6 40619.0 40733.6 375911.0 446462.0 451199.0 452375.0 453386.0 30477.4 30953.8 31068.9 31096.8 31226.8 36548.0 39384.0 41186.0 42877.8 42891.4 30729.8 32340.0 33609.2 35264.6 35661.0 28787.6 34404.7 35301.0 35609.5 35618.6 34167.7 34857.8 35451.0 35461.6 35651.0 25188.6 31202.7 31220.0 31255.5 31379.2 20252.8 20504.5 23840.9 24210.9 24267.0 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | [#2] jquery.html Cycles:180 Average:43414.14 Median:3180.52 stddev:123799.94 (3892.4%) stddev-sans-first:124105.06 18:24:03 INFO - PROCESS | 1913 | Values: 377.4 412.7 413.7 421.0 453.0 19810.4 20137.9 21272.9 21417.7 21489.4 21743.5 21991.0 22099.8 22710.0 22738.5 6550.2 6735.2 6787.7 6819.3 6842.0 6527.2 6640.2 6729.8 6791.7 6794.7 6531.2 6710.6 6755.3 6779.9 6780.0 6620.9 6703.3 6758.8 6763.5 6770.8 6585.9 6667.8 6973.7 7126.4 7146.2 20457.7 25940.0 26630.2 26650.5 26910.7 3129.6 3231.5 3259.1 3296.3 3324.3 15109.5 15919.7 16273.6 16374.2 17132.6 2056.8 2059.5 2063.3 2077.1 2138.5 166181.0 174162.1 176255.0 177034.0 177312.0 18349.3 19607.1 20851.0 20901.0 21296.5 32437.7 32755.8 32997.2 33058.0 33154.0 9128.6 9266.7 10389.3 10565.4 10696.5 7062.3 8802.6 8808.0 8822.8 8955.6 7539.7 9746.8 9784.5 9893.1 9896.9 3915.3 4045.2 4045.7 4047.7 4057.0 721.6 846.2 859.6 868.9 922.2 21085.5 21550.0 21567.6 21568.8 21648.7 912.9 924.5 929.8 937.2 944.1 8737.8 10383.7 10657.6 10695.9 11112.5 707110.0 721617.0 731319.4 760228.0 767123.0 119259.0 156888.0 157090.0 163079.0 168832.2 988.3 1100.8 1191.9 1206.7 1281.3 16198.3 16514.8 19650.6 20702.8 20707.8 67033.7 67905.0 68322.0 68347.3 71073.0 73165.0 82682.2 92436.7 92816.5 94467.5 9011.0 11083.9 11663.5 11866.0 12036.8 13313.7 13348.5 13661.6 13789.8 13791.0 10719.9 11185.9 11421.0 11442.9 11639.7 11268.0 11405.1 11495.3 11790.2 11797.4 8896.0 11509.1 11795.5 11870.3 12024.0 9272.5 11725.5 12015.5 12065.9 12619.6 8398.9 8812.6 9691.1 10034.0 11633.8 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | [#3] mootools.html Cycles:180 Average:6792.25 Median:2433.08 stddev:4252.94 (174.8%) stddev-sans-first:4246.51 18:24:03 INFO - PROCESS | 1913 | Values: 1532.9 1542.7 1559.0 1571.9 1580.5 10774.6 11047.7 11374.0 11493.0 11520.6 11206.4 11355.7 11381.1 11409.3 11454.6 2374.3 2427.8 2431.1 2431.6 2434.6 2359.5 2398.8 2403.7 2408.8 2410.7 2375.5 2399.7 2404.9 2409.3 2413.0 2361.1 2370.0 2385.6 2386.0 2386.9 2007.7 2349.8 2353.7 2405.0 2406.9 9519.7 10034.6 11385.9 11509.3 11537.7 4900.6 4995.0 5728.6 5882.5 6020.5 3571.5 3602.4 3666.5 3686.2 3717.0 1053.5 1161.0 1166.6 1168.7 1227.9 13676.0 13814.0 14762.5 14959.9 15202.5 4659.5 4767.2 4781.9 4789.4 4793.1 11705.0 11763.2 11802.4 11819.0 11821.9 5689.5 5746.3 5780.8 5787.2 5789.3 4306.8 5218.6 5355.8 5363.4 5377.5 3808.4 3996.7 4003.7 4065.8 4079.8 884.0 896.8 1041.1 1049.9 1058.0 930.2 933.2 936.2 948.9 991.7 6390.2 6459.7 6688.9 6833.0 6883.5 946.5 969.5 976.1 982.9 1021.8 4228.5 4306.2 4314.5 4434.8 4463.9 13748.5 14248.6 14254.2 14263.1 14298.9 13528.8 13681.5 14041.4 14087.9 14232.5 10137.9 10270.7 10361.4 10370.3 10399.9 6560.4 6572.2 6600.0 6670.3 6690.0 12341.8 12349.4 12450.9 12488.3 12490.4 11109.7 12593.9 12692.2 13023.4 13065.3 8984.5 9204.9 9473.4 9493.4 9505.6 9911.9 10200.0 10316.2 10357.5 10427.6 9143.4 9189.5 9245.5 9367.0 9497.3 7476.4 8756.9 8922.1 8935.0 9088.0 7881.4 8030.6 8102.8 8103.3 8118.2 5838.4 5844.6 5904.7 6079.9 6139.1 5991.8 6042.9 6094.5 6161.8 6257.9 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | [#4] prototype.html Cycles:180 Average:4703.30 Median:5014.77 stddev:1297.05 (25.9%) stddev-sans-first:1257.98 18:24:03 INFO - PROCESS | 1913 | Values: 305.0 333.1 345.4 383.3 391.8 4638.5 4751.6 4836.8 5067.4 5510.3 4461.6 4921.5 5251.5 5366.9 5395.4 3952.6 4030.3 4432.6 4586.2 4683.7 3933.7 4395.5 4652.8 4671.5 4739.0 3831.7 4144.6 4551.5 4612.7 4644.8 3861.8 3932.6 4272.8 4719.1 4728.9 3587.4 4048.9 4420.4 4475.3 4494.9 4550.3 5271.5 5279.4 5304.8 5313.4 4616.1 4798.0 5175.1 5428.6 5451.2 5094.8 5340.6 5769.0 5773.0 5785.7 4210.9 4220.6 4510.5 4522.9 4603.9 5109.7 5474.8 6108.4 6114.8 6130.5 4553.8 4697.7 4715.4 4724.6 4725.6 4227.9 4239.6 4695.9 4705.3 4804.9 4500.6 4999.4 5112.3 5145.8 5155.7 4687.1 5003.5 5313.5 5689.3 5778.3 1441.3 1513.8 1606.7 1607.1 1625.5 1051.0 1085.7 1156.7 1202.9 1224.4 4982.0 5147.7 5264.3 5356.4 5361.8 5137.9 5482.9 5817.1 5940.2 5993.8 4815.7 4867.6 5130.7 5360.4 5403.4 2704.4 3087.4 3095.1 3103.0 3117.0 5134.8 5605.4 5760.0 6121.9 6231.9 4872.0 4944.2 5661.7 5787.5 5819.0 5515.6 5578.1 6150.9 6249.3 6273.7 4974.8 5133.9 5283.7 5754.5 5917.2 4935.9 4993.9 5540.4 5562.9 5908.5 5067.8 5502.6 5577.8 5781.2 6190.9 4195.0 4386.3 4509.1 4648.5 4650.8 5157.0 5348.7 5544.9 5757.0 5927.0 4673.6 5152.6 5189.7 5779.3 5783.1 4937.7 5145.9 5326.7 5419.3 5745.4 4711.7 5026.0 5054.0 5516.5 5621.3 4812.6 4836.6 5230.0 5694.7 5724.3 4853.3 5202.7 5526.0 5579.6 5611.9 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - PROCESS | 1913 | [#5] yui.html Cycles:180 Average:20507.17 Median:3566.89 stddev:29477.81 (826.4%) stddev-sans-first:29522.89 18:24:03 INFO - PROCESS | 1913 | Values: 674.9 702.7 712.9 797.8 818.9 14748.3 15886.3 16047.3 16100.5 16148.0 14498.9 15252.9 15375.8 15391.5 15410.2 867.1 950.5 950.7 951.7 952.5 891.8 925.3 930.5 945.0 946.2 847.6 991.4 994.2 994.7 995.8 980.2 981.5 991.6 991.7 994.0 728.6 729.9 730.3 732.2 732.5 13807.3 15664.7 15907.4 16071.8 16378.9 13993.7 14313.6 14389.3 14396.6 14489.3 11580.0 11637.7 11836.7 11848.6 11899.9 3619.4 3644.2 3655.6 3763.9 3833.4 79300.6 104999.0 112851.0 115388.0 116054.7 10550.7 10643.3 10655.7 10867.1 11127.4 22404.4 22426.6 22561.9 22784.8 22802.2 7373.5 7570.6 7646.5 7686.9 7892.0 4204.3 4234.3 4319.9 4361.8 4506.7 3429.4 3439.1 3498.4 3508.1 3514.3 511.4 523.7 527.2 527.7 529.7 628.0 635.7 653.3 662.3 704.6 9582.0 9602.8 9876.5 10387.9 10669.5 553.9 558.9 592.6 635.6 700.9 4756.9 5079.7 5083.7 5085.0 5087.7 101293.0 101865.0 103466.0 104644.5 106455.0 90569.5 92698.0 93864.5 95821.5 96188.0 69153.0 70926.9 72636.6 79367.6 81057.0 10136.4 10889.9 11098.9 11377.5 11453.7 47195.3 47963.0 48716.5 48874.5 51184.5 63471.4 63628.0 65066.3 65521.7 65846.7 12147.8 12175.3 12279.3 12552.3 12955.7 14349.8 14466.9 14473.0 14609.7 15150.4 11281.9 12899.9 13497.6 13543.2 13719.0 12903.8 13252.9 13444.3 13670.2 13764.6 12362.2 13050.8 13337.5 13433.6 13605.6 10895.6 11078.5 11228.4 11356.6 11625.4 10431.5 10662.9 11249.2 11307.3 11310.7 18:24:03 INFO - PROCESS | 1913 | -------- Summary: end -------- 18:24:03 INFO - PROCESS | 1913 | 18:24:03 INFO - TEST-INFO | 1913: exit 0 18:24:03 INFO - TEST-OK | dromaeo_css | took 1116706ms 18:24:03 INFO - TEST-START | kraken 18:24:03 INFO - operating with platform_type : mac_ 18:24:03 INFO - Initialising browser for kraken test... 18:24:03 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpL7FqCv/profile http://localhost:51550/getInfo.html) 18:24:06 INFO - PROCESS | 1919 | 2017-03-14 18:24:06.600 plugin-container[1920:15533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8933, name = 'com.apple.tsm.portname' 18:24:06 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:06 INFO - PROCESS | 1919 | 2017-03-14 18:24:06.604 plugin-container[1920:15533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 18:24:06 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:06 INFO - PROCESS | 1919 | 2017-03-14 18:24:06.604 plugin-container[1920:15533] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:06 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 18:24:06 INFO - PROCESS | 1919 | colorDepth:24 18:24:06 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 18:24:06 INFO - PROCESS | 1919 | __metrics 18:24:06 ERROR - PROCESS | 1919 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 18:24:07 INFO - TEST-INFO | 1919: exit 0 18:24:07 INFO - Browser initialized. 18:24:07 INFO - Running cycle 1/1 for kraken test... 18:24:07 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpL7FqCv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:09 INFO - PROCESS | 1921 | 2017-03-14 18:24:09.848 plugin-container[1922:15661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 18:24:09 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:09 INFO - PROCESS | 1921 | 2017-03-14 18:24:09.848 plugin-container[1922:15661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.CFPasteboardClient' 18:24:09 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:09 INFO - PROCESS | 1921 | 2017-03-14 18:24:09.848 plugin-container[1922:15661] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:19 INFO - PROCESS | 1921 | RSS: Main: 220241920 18:24:19 INFO - PROCESS | 1921 | 18:24:22 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51550/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:23 INFO - PROCESS | 1921 | RSS: Main: 235069440 18:24:23 INFO - PROCESS | 1921 | 18:24:26 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51550/tests/kraken/driver.html?testName=audio-dft) 18:24:27 INFO - PROCESS | 1921 | RSS: Main: 243326976 18:24:27 INFO - PROCESS | 1921 | 18:24:30 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51550/tests/kraken/driver.html?testName=audio-fft) 18:24:30 INFO - PROCESS | 1921 | RSS: Main: 246951936 18:24:30 INFO - PROCESS | 1921 | 18:24:32 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51550/tests/kraken/driver.html?testName=audio-oscillator) 18:24:33 INFO - PROCESS | 1921 | RSS: Main: 247267328 18:24:33 INFO - PROCESS | 1921 | 18:24:35 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51550/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:36 INFO - PROCESS | 1921 | RSS: Main: 248627200 18:24:36 INFO - PROCESS | 1921 | 18:24:39 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51550/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:40 INFO - PROCESS | 1921 | RSS: Main: 252755968 18:24:40 INFO - PROCESS | 1921 | 18:24:43 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51550/tests/kraken/driver.html?testName=imaging-desaturate) 18:24:43 INFO - PROCESS | 1921 | RSS: Main: 252260352 18:24:43 INFO - PROCESS | 1921 | 18:24:46 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51550/tests/kraken/driver.html?testName=json-parse-financial) 18:24:47 INFO - PROCESS | 1921 | RSS: Main: 251875328 18:24:47 INFO - PROCESS | 1921 | 18:24:49 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51550/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:24:49 INFO - PROCESS | 1921 | RSS: Main: 251650048 18:24:49 INFO - PROCESS | 1921 | 18:24:51 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:24:52 INFO - PROCESS | 1921 | RSS: Main: 251674624 18:24:52 INFO - PROCESS | 1921 | 18:24:54 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:24:55 INFO - PROCESS | 1921 | RSS: Main: 251748352 18:24:55 INFO - PROCESS | 1921 | 18:24:58 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:24:58 INFO - PROCESS | 1921 | RSS: Main: 252047360 18:24:58 INFO - PROCESS | 1921 | 18:25:01 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:02 INFO - PROCESS | 1921 | RSS: Main: 251875328 18:25:02 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51550/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51550/tests/kraken/driver.html?testName=ai-astar) 18:25:04 INFO - PROCESS | 1921 | RSS: Main: 253833216 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | __start_tp_report 18:25:04 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 18:25:04 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 18:25:04 INFO - PROCESS | 1921 | |i|pagename|runs| 18:25:04 INFO - PROCESS | 1921 | |0;ai-astar;110;119;121;120;120;152;179;149;122;121 18:25:04 INFO - PROCESS | 1921 | |1;audio-beat-detection;147;114;116;163;148;114;145;142;133;113 18:25:04 INFO - PROCESS | 1921 | |2;audio-dft;197;199;199;196;195;197;202;199;199;193 18:25:04 INFO - PROCESS | 1921 | |3;audio-fft;82;52;73;114;89;86;72;62;74;62 18:25:04 INFO - PROCESS | 1921 | |4;audio-oscillator;86;71;85;74;71;75;113;101;79;86 18:25:04 INFO - PROCESS | 1921 | |5;imaging-gaussian-blur;91;90;92;101;113;94;137;137;92;91 18:25:04 INFO - PROCESS | 1921 | |6;imaging-darkroom;96;98;99;105;97;98;98;99;98;99 18:25:04 INFO - PROCESS | 1921 | |7;imaging-desaturate;89;93;91;91;95;100;90;91;90;92 18:25:04 INFO - PROCESS | 1921 | |8;json-parse-financial;66;68;67;65;70;69;70;70;70;70 18:25:04 INFO - PROCESS | 1921 | |9;json-stringify-tinderbox;59;65;62;73;63;59;54;59;68;60 18:25:04 INFO - PROCESS | 1921 | |10;stanford-crypto-aes;93;103;89;84;82;75;87;101;78;95 18:25:04 INFO - PROCESS | 1921 | |11;stanford-crypto-ccm;139;142;138;145;160;164;156;163;180;160 18:25:04 INFO - PROCESS | 1921 | |12;stanford-crypto-pbkdf2;168;167;191;196;180;223;181;175;167;215 18:25:04 INFO - PROCESS | 1921 | |13;stanford-crypto-sha256-iterative;67;65;66;66;68;78;84;89;70;63 18:25:04 INFO - PROCESS | 1921 | __end_tp_report 18:25:04 INFO - PROCESS | 1921 | __start_cc_report 18:25:04 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,997 18:25:04 INFO - PROCESS | 1921 | __end_cc_report 18:25:04 INFO - PROCESS | 1921 | __startTimestamp1489541104633__endTimestamp 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | ------- Summary: start ------- 18:25:04 INFO - PROCESS | 1921 | Number of tests: 14 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#0] ai-astar Cycles:10 Average:131.30 Median:121.50 stddev:21.54 (17.7%) stddev-sans-first:21.42 18:25:04 INFO - PROCESS | 1921 | Values: 110.0 119.0 121.0 120.0 120.0 152.0 179.0 149.0 122.0 121.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:143.50 stddev:18.12 (12.6%) stddev-sans-first:18.55 18:25:04 INFO - PROCESS | 1921 | Values: 147.0 114.0 116.0 163.0 148.0 114.0 145.0 142.0 133.0 113.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#2] audio-dft Cycles:10 Average:197.60 Median:199.00 stddev:2.55 (1.3%) stddev-sans-first:2.69 18:25:04 INFO - PROCESS | 1921 | Values: 197.0 199.0 199.0 196.0 195.0 197.0 202.0 199.0 199.0 193.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#3] audio-fft Cycles:10 Average:76.60 Median:73.50 stddev:17.46 (23.7%) stddev-sans-first:18.41 18:25:04 INFO - PROCESS | 1921 | Values: 82.0 52.0 73.0 114.0 89.0 86.0 72.0 62.0 74.0 62.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:77.00 stddev:13.67 (17.8%) stddev-sans-first:14.49 18:25:04 INFO - PROCESS | 1921 | Values: 86.0 71.0 85.0 74.0 71.0 75.0 113.0 101.0 79.0 86.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#5] imaging-gaussian-blur Cycles:10 Average:103.80 Median:91.00 stddev:18.83 (20.7%) stddev-sans-first:19.39 18:25:04 INFO - PROCESS | 1921 | Values: 91.0 90.0 92.0 101.0 113.0 94.0 137.0 137.0 92.0 91.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:98.00 stddev:2.41 (2.5%) stddev-sans-first:2.35 18:25:04 INFO - PROCESS | 1921 | Values: 96.0 98.0 99.0 105.0 97.0 98.0 98.0 99.0 98.0 99.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:91.00 stddev:3.22 (3.5%) stddev-sans-first:3.21 18:25:04 INFO - PROCESS | 1921 | Values: 89.0 93.0 91.0 91.0 95.0 100.0 90.0 91.0 90.0 92.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:70.00 stddev:1.90 (2.7%) stddev-sans-first:1.79 18:25:04 INFO - PROCESS | 1921 | Values: 66.0 68.0 67.0 65.0 70.0 69.0 70.0 70.0 70.0 70.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.50 stddev:5.39 (8.6%) stddev-sans-first:5.59 18:25:04 INFO - PROCESS | 1921 | Values: 59.0 65.0 62.0 73.0 63.0 59.0 54.0 59.0 68.0 60.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:85.50 stddev:9.35 (10.9%) stddev-sans-first:9.78 18:25:04 INFO - PROCESS | 1921 | Values: 93.0 103.0 89.0 84.0 82.0 75.0 87.0 101.0 78.0 95.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#11] stanford-crypto-ccm Cycles:10 Average:154.70 Median:160.00 stddev:13.47 (8.4%) stddev-sans-first:13.04 18:25:04 INFO - PROCESS | 1921 | Values: 139.0 142.0 138.0 145.0 160.0 164.0 156.0 163.0 180.0 160.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.30 Median:186.00 stddev:19.89 (10.7%) stddev-sans-first:19.97 18:25:04 INFO - PROCESS | 1921 | Values: 168.0 167.0 191.0 196.0 180.0 223.0 181.0 175.0 167.0 215.0 18:25:04 INFO - PROCESS | 1921 | 18:25:04 INFO - PROCESS | 1921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:69.00 stddev:8.91 (12.9%) stddev-sans-first:9.29 18:25:04 INFO - PROCESS | 1921 | Values: 67.0 65.0 66.0 66.0 68.0 78.0 84.0 89.0 70.0 63.0 18:25:04 INFO - PROCESS | 1921 | -------- Summary: end -------- 18:25:04 INFO - PROCESS | 1921 | 18:25:05 INFO - TEST-INFO | 1921: exit 0 18:25:05 INFO - TEST-OK | kraken | took 61725ms 18:25:05 INFO - SUITE-END | took 1178s 18:25:05 INFO - Completed test suite (00:19:38) 18:25:05 INFO - javascript benchmark 18:25:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7699.416946153064, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3502.0019294501058, 3537.204129070959, 3545.663161999609, 3549.893503194905, 3572.8570857165714, 6091.177691011712, 6144.508439324854, 6156.0, 6166.7533298667995, 6184.381561843813, 6098.14625952367, 6152.138700581911, 6175.505959523239, 6180.1656776335185, 6200.720967556459, 5470.7264636768205, 5561.221428999943, 5575.275214222166, 5586.581006424514, 5647.237622920894, 5640.858978525549, 5643.068893632546, 5651.9717401413145, 5658.321001479828, 5697.344805347397, 5139.074966506046, 5166.741662916839, 5356.625036247464, 5389.51494365507, 5646.661200327973, 5466.398696143421, 5478.945210547883, 5485.040117979377, 5533.501984821345, 5552.361478429992, 5501.092319767234, 5562.082256427685, 5581.804636837732, 5601.915971260434, 5608.551315894719, 6171.814845554641, 6227.782027629057, 6233.407826256498, 6241.407066328691, 6285.2457705075685, 6275.529335299825, 6293.527985401113, 6373.139626150457, 6541.9345806541805, 6553.6395498247575, 6510.967445162792, 6595.967020164869, 6599.406053455212, 6601.306862779387, 6602.867942641168, 6166.907496387557, 6206.782762603287, 6261.9373806262265, 6276.717547710409, 6277.968610157011, 6467.4179323860135, 6476.805695829133, 6621.602703837785, 6749.426298764562, 6772.72909083641, 6047.5464340173785, 6314.526410519229, 6381.617102973743, 6386.584871983307, 6404.935950640527, 6509.121268628769, 6509.1863517060365, 6525.804225873231, 6532.510061745388, 6540.149780528596, 6379.617222966637, 6388.936110638927, 6412.102305677209, 6445.0, 6453.45145662624, 5150.4334523202415, 5305.893882122413, 6304.306526282106, 6309.968450157719, 6389.3291204423795, 4804.663673542818, 5073.34046573943, 5142.254373115878, 5203.7918483261, 5272.947270527322, 4345.630621397154, 4454.331850222427, 4621.353010578522, 4646.512116227815, 4662.020975595163, 6218.751249950042, 6268.4671802897265, 6269.122322874801, 6276.278228003746, 6299.118123462719, 5504.311961004874, 6318.652474113968, 6649.3018233085795, 6652.135222421054, 6677.098591690156, 6108.251008765781, 6117.694115294217, 6126.3955342902, 6140.534923917717, 6146.5390095743, 5839.328477225088, 5866.7653293868625, 5876.265466816648, 5880.294364676266, 5903.35063143054, 6830.21452532965, 6840.452763778886, 6841.589504629738, 6867.175938887365, 6871.5189936703955, 6261.186045814197, 6381.617102973836, 6497.122888410193, 6501.2523559791225, 6553.5084868634085, 6000.609960352563, 6843.110395648733, 6853.7943861684225, 6859.725610975505, 6865.27914568983, 6490.772822951174, 6504.0, 6512.967435162794, 6518.0, 6520.902186467112, 6278.905816412849, 6296.370362963667, 6324.304326523987, 6437.742490300336, 6569.671516424255, 5693.800716974886, 6076.57463977535, 6336.968315158395, 6370.904436433365, 6381.298057213796, 6330.49356051524, 6369.20384951881, 6369.235691717024, 6378.1070650108095, 6389.073584330339, 4899.92201715622, 5823.18475413451, 6127.877442451215, 6146.5248340398875, 6151.108089327112, 6279.215098112736, 6290.748370065146, 6383.616982981036, 6401.231852177582, 6402.519811014099, 6265.404786545271, 6278.937210627835, 6287.056941458818, 6296.0555916612875, 6301.6219026858535], "value": 5983.070072822451, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.7414404958265, 765.6095391350411, 811.6834434570517, 954.1937063181609, 956.2445667922342, 35546.64453355466, 36564.81717591412, 37411.064723381925, 37496.81251593742, 37574.4363834542, 36707.0, 36881.81559092201, 36944.0, 36961.63038369615, 37041.0, 7704.383649308056, 10254.641087561937, 10446.320989135682, 10617.57529698811, 10671.83992240117, 10346.948265258663, 10391.428471434068, 10461.581536738522, 10464.0, 10470.16238700902, 10442.00800923911, 10449.164066874631, 10462.163026957825, 10466.848646648863, 10470.581176752921, 10392.584296628163, 10401.115905148034, 10409.375437473776, 10444.895551044507, 10448.947755261175, 10234.181265498743, 10282.48587570626, 10290.897091029068, 10301.793964120676, 10305.639302624448, 31696.524552131737, 31733.682663173302, 31867.203319916956, 32012.679873201203, 32086.679133208603, 3665.6334366563397, 3691.575468821093, 3730.1607138393915, 3756.530433695788, 3770.7549009314307, 19777.0, 24037.639435408484, 24329.27012189637, 25160.370990725194, 25288.620670689954, 2653.4162484253625, 3073.8155710657206, 3156.636986746508, 3264.167637252509, 3272.934541309184, 321933.0, 324741.0, 368791.1560442207, 400887.0, 407424.9628751867, 41949.53176638864, 41986.79006604947, 42122.0, 42158.78920605377, 42180.523681670995, 32688.509672354932, 35690.821545892104, 37371.31829067699, 43420.69737907868, 43449.0, 10963.50664220112, 13112.213267203999, 13141.54004609834, 13177.538786142439, 13242.337883105805, 10391.324563903323, 11133.443327833576, 11459.369734664508, 11683.76632467346, 11715.589954351728, 11555.88444115548, 11563.479643416067, 11586.073114150848, 11615.477303521362, 11665.59170429031, 5590.496855283044, 5635.718214089279, 5673.517750991129, 5679.261695979496, 5685.630434021776, 777.8483195776769, 917.6467060181744, 933.169479163545, 942.189716843514, 977.2231076294363, 26297.342566435993, 27300.180994570193, 27305.0, 27574.724252757616, 27615.723842761316, 928.8003079337975, 990.8166989107038, 1042.421456091861, 1064.531606093332, 1095.9697883989024, 12910.483580656857, 14420.351084201237, 14885.851141488447, 15115.31981060855, 15348.539543813704, 418761.9061904671, 419743.0, 421169.0, 423030.0, 427942.0, 53095.0, 53190.404287871424, 53309.46690533045, 53315.46684533105, 53319.20021199685, 1388.569543441516, 1422.1467119728081, 1433.383645032625, 1436.1167881752647, 1441.992790036064, 27678.0, 28411.289717757223, 28470.288242794097, 28511.42977140487, 28825.135245942656, 40501.1899762009, 40586.391204131374, 40602.593974059884, 40619.0, 40733.59266407298, 375911.0, 446462.0, 451199.0, 452375.0, 453386.0, 30477.390452191277, 30953.845230773702, 31068.91258805931, 31096.844515777277, 31226.843865780527, 36548.0, 39384.0, 41186.0, 42877.78561107175, 42891.35662965121, 30729.846350768996, 32340.0, 33609.15977100494, 35264.589416423056, 35661.0, 28787.568186477638, 34404.655953440146, 35301.0, 35609.46585801267, 35618.64381356257, 34167.65832341744, 34857.825710871286, 35451.0, 35461.645383546864, 35651.0, 25188.622170667088, 31202.687973119977, 31220.0, 31255.53116703206, 31379.215519612193, 20252.79747202509, 20504.487387815425, 23840.880795595913, 24210.87894560516, 24267.0], "value": 17917.568170177645, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.43384922616076, 412.73997381649576, 413.65666496807654, 420.9663226941844, 453.03955614098095, 19810.40568782939, 20137.899310503464, 21272.89363553184, 21417.678734818972, 21489.355319340404, 21743.45641358967, 21990.99999999996, 22099.779002209973, 22710.0, 22738.54522909541, 6550.246721627026, 6735.158105236846, 6787.660616969157, 6819.318068193191, 6842.0, 6527.249366322886, 6640.169978752656, 6729.7644582439625, 6791.728330866759, 6794.728210871559, 6531.216254049521, 6710.563813352116, 6755.256921738604, 6779.864402711943, 6779.966100169517, 6620.867582648344, 6703.296153903843, 6758.8310292242595, 6763.526553141282, 6770.796876093725, 6585.934140658628, 6667.833304167386, 6973.721051157948, 7126.3942564882045, 7146.178189508221, 20457.693134602992, 25940.0, 26630.20109396721, 26650.46699066008, 26910.73089269102, 3129.5618613394145, 3231.4829627259523, 3259.1200375898375, 3296.258341873059, 3324.2852786650988, 15109.47116850905, 15919.681606367923, 16273.593160170973, 16374.181290935525, 17132.571685707833, 2056.7737548869613, 2059.4542446251753, 2063.2778527515397, 2077.1172251793078, 2138.4653836540865, 166181.0, 174162.1291893545, 176255.0, 177033.99999999872, 177312.0, 18349.330133973257, 19607.117679704446, 20851.0, 20901.01440363163, 21296.467588310108, 32437.675623243937, 32755.836220818743, 32997.17507062294, 33058.00825975225, 33154.0, 9128.58921348541, 9266.721998340061, 10389.324693894872, 10565.418901960313, 10696.465176741132, 7062.293770622897, 8802.60388282516, 8807.955960220157, 8822.779430514289, 8955.59699813497, 7539.736109236151, 9746.832279509188, 9784.461854597923, 9893.060159284856, 9896.851547226797, 3915.314819906505, 4045.1505183911117, 4045.7458187961824, 4047.7166598337835, 4057.0, 721.585088574072, 846.2172490446312, 859.6002858670623, 868.9217970382696, 922.2115091596722, 21085.472863178544, 21550.0, 21567.56864862694, 21568.784312156993, 21648.675269870964, 912.9452232866049, 924.4822899176348, 929.8279818233649, 937.1846493550654, 944.1455482788087, 8737.825243495094, 10383.740406489898, 10657.561229233954, 10695.94652026735, 11112.499937502831, 707110.0, 721617.0, 731319.3736125354, 760228.0, 767123.0, 119259.0, 156888.0, 157090.0, 163079.0, 168832.15583922248, 988.2538683294067, 1100.763335882773, 1191.8688944216128, 1206.6621346023128, 1281.2504684759333, 16198.29917858608, 16514.75227871559, 19650.606987859876, 20702.792972070085, 20707.79292207059, 67033.66483167748, 67905.0, 68322.0, 68347.3165268341, 71073.0, 73165.0, 82682.17317826985, 92436.68908277348, 92816.53591731998, 94467.52766236124, 9010.954945225232, 11083.889161108607, 11663.475143618727, 11865.991390731884, 12036.75926481483, 13313.733725325246, 13348.466061357436, 13661.590152295446, 13789.793153102511, 13791.0, 10719.89280107189, 11185.888141118485, 11421.0, 11442.94278528602, 11639.6508104757, 11268.042216411697, 11405.144614154138, 11495.252808567417, 11790.174687771774, 11797.410129493663, 8895.955520222358, 11509.13681473876, 11795.469203886018, 11870.347130907883, 12024.0, 9272.490013049348, 11725.530978760755, 12015.459304331502, 12065.939670301592, 12619.558315459282, 8398.916010839814, 8812.647494100163, 9691.12779849817, 10033.996600339908, 11633.767324653629], "value": 11543.246017284622, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1532.923353832308, 1542.7377345851198, 1559.0411896683536, 1571.8742500599953, 1580.4863419388698, 10774.5690172393, 11047.668569942894, 11374.0332071774, 11492.999999999989, 11520.596779112733, 11206.383648899327, 11355.659330220085, 11381.089512838973, 11409.31544107354, 11454.599089031886, 2374.2995816234165, 2427.8421902576274, 2431.0761910473993, 2431.5623187826186, 2434.5982912819363, 2359.528094381122, 2398.796102331304, 2403.663487111797, 2408.7952524035477, 2410.7227668818045, 2375.4655202579456, 2399.748026457223, 2404.903803847844, 2409.3253888911045, 2412.9879350603223, 2361.0791791201445, 2370.0282884017556, 2385.6421536769535, 2386.0455817672896, 2386.904523819045, 2007.6888082347114, 2349.835511514195, 2353.658719485682, 2405.025964484387, 2406.8796560172095, 9519.714408567685, 10034.595156677999, 11385.9183377579, 11509.251898626646, 11537.653870383818, 4900.607951363918, 4995.000999800054, 5728.627639203439, 5882.47057764799, 6020.548458865603, 3571.4642803579673, 3602.441621548664, 3666.5416822897137, 3686.225892562565, 3716.9964109690504, 1053.5101177952213, 1160.9883901160965, 1166.5742004168503, 1168.6793922867191, 1227.9140460167791, 13676.042677012612, 13814.0, 14762.48331308399, 14959.850401496064, 15202.543923682308, 4659.487456379796, 4767.165745994437, 4781.904361912742, 4789.377380940456, 4793.089313030513, 11705.005074568588, 11763.235389699643, 11802.40987950599, 11818.995385392165, 11821.88178118225, 5689.544836413077, 5746.281714785652, 5780.826575202751, 5787.160861675117, 5789.305283366023, 4306.82772689089, 5218.556422704037, 5355.758990845421, 5363.410024897258, 5377.462253774591, 3808.4287356896134, 3996.740211886276, 4003.7197396181987, 4065.756054636672, 4079.836806527765, 883.9646414143491, 896.7668406214431, 1041.073444634276, 1049.884512703602, 1057.9576816927238, 930.2000279759402, 933.1601558597181, 936.194872409727, 948.8529277961927, 991.6628346362271, 6390.201224846895, 6459.709313080923, 6688.85291119509, 6832.965835170892, 6883.483738719516, 946.4747065378643, 969.4958621516772, 976.0873583199731, 982.9017098290257, 1021.7598864266891, 4228.450301460852, 4306.246406878847, 4314.460692413449, 4434.756088415168, 4463.910721785546, 13748.450061997608, 14248.643783905278, 14254.216018118896, 14263.144211347353, 14298.928505357615, 13528.797068043987, 13681.452741890213, 14041.438342466392, 14087.929560352337, 14232.501862434972, 10137.898621013843, 10270.691879243635, 10361.378317300987, 10370.27408081427, 10399.94800025995, 6560.44236239925, 6572.244191917799, 6600.009998500072, 6670.299618540177, 6690.0, 12341.814872776737, 12349.444275007467, 12450.875491244971, 12488.25070495773, 12490.437930293341, 11109.722256943318, 12593.93703031479, 12692.17500862478, 13023.413946372246, 13065.346732663518, 8984.505852178192, 9204.907950920673, 9473.384230025302, 9493.382930109521, 9505.619775208914, 9911.900880991097, 10200.0, 10316.17470602335, 10357.533910974174, 10427.635032773816, 9143.3599648024, 9189.494577798285, 9245.537723113683, 9366.95316523413, 9497.287703422133, 7476.364509016795, 8756.912430875609, 8922.107789221025, 8935.017148113833, 9087.954560227157, 7881.369490440637, 8030.558319292495, 8102.83794324122, 8103.31121854649, 8118.188181181835, 5838.416158384298, 5844.620099693507, 5904.704764761831, 6079.939200607937, 6139.079138129317, 5991.820245392645, 6042.909356359746, 6094.481969032682, 6161.753529858755, 6257.874842503215], "value": 5160.314075632529, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.04672897196264, 333.0723933844244, 345.4472843450481, 383.28899890702746, 391.7845185148168, 4638.512956139603, 4751.552950230625, 4836.766624510745, 5067.442581316052, 5510.283663123793, 4461.553844615537, 4921.532454416833, 5251.529571719915, 5366.91949620755, 5395.433479484656, 3952.6245006724316, 4030.3349947258666, 4432.578905004019, 4586.197415452282, 4683.7189768613825, 3933.6656384207377, 4395.538468460813, 4652.767361631922, 4671.481768425276, 4738.9526104739025, 3831.731778775487, 4144.5855414458465, 4551.522090180533, 4612.654050946176, 4644.790984405693, 3861.7876016819064, 3932.6460618544324, 4272.807723652443, 4719.144917325038, 4728.855311218036, 3587.4080776671817, 4048.9472737088277, 4420.403245561873, 4475.283954567253, 4494.9325760113625, 4550.270897059129, 5271.472852714698, 5279.445658205871, 5304.814331498379, 5313.38896026958, 4616.130467160805, 4797.9520204797855, 5175.068487672217, 5428.5928555358505, 5451.182322651594, 5094.79620815167, 5340.62615616907, 5769.04810706233, 5772.977509566662, 5785.6528608283215, 4210.873673789761, 4220.556841531657, 4510.503844577094, 4522.909541809146, 4603.861884143481, 5109.693418394871, 5474.753636086386, 6108.358622344635, 6114.798672477905, 6130.509559235251, 4553.840615578439, 4697.718136911796, 4715.434147902205, 4724.551167639069, 4725.5510726480925, 4227.852025179104, 4239.576042395735, 4695.929561056587, 4705.282571861246, 4804.876830060489, 4500.572445617661, 4999.400071991385, 5112.284280200774, 5145.816462213637, 5155.74221288934, 4687.086018226481, 5003.524665156804, 5313.521783039545, 5689.317281926195, 5778.306603207558, 1441.3153751967732, 1513.7805018272288, 1606.7188242057593, 1607.100023986548, 1625.520471460922, 1051.033049594375, 1085.733995171188, 1156.7166044319197, 1202.903767698582, 1224.4306397525193, 4981.95378970413, 5147.742612869341, 5264.341957255343, 5356.4107948125675, 5361.809723214681, 5137.948620513822, 5482.945170548323, 5817.069268916953, 5940.247626949974, 5993.820185394445, 4815.662903596714, 4867.561919427269, 5130.692158470429, 5360.373417025629, 5403.378611459654, 2704.35095577064, 3087.4133914556164, 3095.1488340706037, 3102.968970310268, 3116.9532457013165, 5134.762542206928, 5605.411431799684, 5759.971200144057, 6121.938780612226, 6231.912309376319, 4872.0, 4944.24479309847, 5661.745221465044, 5787.4729633200195, 5818.963362823063, 5515.558755299567, 5578.079616863172, 6150.938490615036, 6249.250089989139, 6273.686315684197, 4974.751262436791, 5133.948660513347, 5283.656562323436, 5754.4533269339345, 5917.230760001173, 4935.926910697443, 4993.880048959551, 5540.418256083214, 5562.944370556243, 5908.497777688773, 5067.822626208065, 5502.6433875335215, 5577.832665020056, 5781.248437703071, 6190.907136392868, 4194.958050419579, 4386.320120381346, 4509.07563949383, 4648.511906249931, 4650.767461626972, 5157.0, 5348.679079255257, 5544.892940682515, 5756.963746525666, 5927.0, 4673.556012178974, 5152.5877929766275, 5189.662671926313, 5779.306483222041, 5783.062674004255, 4937.703737775601, 5145.948540514547, 5326.7336633167415, 5419.295491586068, 5745.432381256827, 4711.667724476406, 5026.0199261143725, 5053.974730126326, 5516.503514683698, 5621.269234999424, 4812.614990800657, 4836.564709176191, 5229.9738501308775, 5694.686792226467, 5724.255846739898, 4853.344798452305, 5202.73986300691, 5525.9723701382845, 5579.553635709215, 5611.8877622448135], "value": 4315.179512575895, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.9493787965903, 702.7118881258685, 712.8859382498802, 797.7845981584973, 818.9099199088101, 14748.262586870655, 15886.285117169726, 16047.277872495752, 16100.516984490454, 16148.03111813292, 14498.927505362459, 15252.923735381335, 15375.769363459556, 15391.461298854514, 15410.229488525556, 867.1198733285704, 950.5437390052745, 950.6910254167389, 951.6764300137952, 952.4951775558965, 891.7547674389563, 925.3152667026386, 930.525432029663, 944.9622015119387, 946.186279799372, 847.631280393031, 991.4447909170888, 994.2344394815988, 994.7363948553639, 995.7610173558367, 980.2060331131806, 981.5141504955093, 991.5587563534231, 991.6777047459568, 993.9910990344806, 728.6466063958976, 729.854029194158, 730.3135053050116, 732.2128711634961, 732.4762794601885, 13807.309634518335, 15664.686706265924, 15907.363705451768, 16071.839281607268, 16378.918105409515, 13993.72012559743, 14313.642158946006, 14389.280535973265, 14396.568102956928, 14489.347979340955, 11580.0, 11637.650870473899, 11836.704082397922, 11848.585299514476, 11899.881001189964, 3619.438986957026, 3644.1618427761764, 3655.5796083450477, 3763.9059023524355, 3833.4058220975794, 79300.60349698215, 104999.0, 112851.0, 115387.99999999916, 116054.67890642224, 10550.683479495474, 10643.25497215203, 10655.733606659896, 10867.076298514714, 11127.387993660426, 22404.43988900258, 22426.55146897053, 22561.871906404944, 22784.772152278267, 22802.201922932618, 7373.483856130019, 7570.5836179010685, 7646.541207527566, 7686.923130768732, 7892.0, 4204.285271503791, 4234.301340278819, 4319.913601727948, 4361.803718832597, 4506.7070640408265, 3429.3998550253605, 3439.1230236290085, 3498.440249560109, 3508.08789714676, 3514.332276867387, 511.3863363963259, 523.7250443517185, 527.1854984049653, 527.7097596322084, 529.7457220534088, 627.9529035322369, 635.6726285962733, 653.3368630839701, 662.2847324889108, 704.5807744392079, 9581.952090239503, 9602.759931001641, 9876.456794876207, 10387.94806025965, 10669.466526673636, 553.9202036676094, 558.8910162518351, 592.570386469806, 635.5995129050509, 700.8903201363829, 4756.857294281177, 5079.6698214615935, 5083.72039537829, 5084.9745751271, 5087.694738315713, 101293.0, 101865.0, 103466.0, 104644.47677761562, 106455.0, 90569.54715226514, 92698.0, 93864.53067734618, 95821.52089239508, 96188.0, 69153.0, 70926.87219383426, 72636.63681681664, 79367.60316198382, 81057.0, 10136.44249566281, 10889.891101089204, 11098.944505277423, 11377.54489820431, 11453.656390308304, 47195.29207061965, 47963.0, 48716.512834871195, 48874.511254886995, 51184.48815511797, 63471.36528634655, 63628.04557931727, 65066.34933650792, 65521.67239163774, 65846.67076664585, 12147.817782733442, 12175.269483830998, 12279.324637145042, 12552.309622970824, 12955.676108097374, 14349.784753228918, 14466.927665361605, 14472.986890917535, 14609.707805844037, 15150.393984240507, 11281.887181128084, 12899.935500322814, 13497.595072147458, 13543.187408755506, 13719.0, 12903.832914827803, 13252.867471325164, 13444.327783610976, 13670.17978921268, 13764.633089964113, 12362.196457230611, 13050.804237936249, 13337.53318633843, 13433.596992090253, 13605.591832245049, 10895.618653347095, 11078.45084409136, 11228.382438965935, 11356.602518911799, 11625.418729063344, 10431.530581123716, 10662.946685266525, 11249.1563132767, 11307.321560706385, 11310.660680179608], "value": 7559.667042340246, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 119.0, 121.0, 120.0, 120.0, 152.0, 179.0, 149.0, 122.0, 121.0], "value": 131.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 116.0, 163.0, 148.0, 114.0, 145.0, 142.0, 133.0, 113.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 199.0, 196.0, 195.0, 197.0, 202.0, 199.0, 199.0, 193.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 52.0, 73.0, 114.0, 89.0, 86.0, 72.0, 62.0, 74.0, 62.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 85.0, 74.0, 71.0, 75.0, 113.0, 101.0, 79.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 92.0, 101.0, 113.0, 94.0, 137.0, 137.0, 92.0, 91.0], "value": 103.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 99.0, 105.0, 97.0, 98.0, 98.0, 99.0, 98.0, 99.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 91.0, 91.0, 95.0, 100.0, 90.0, 91.0, 90.0, 92.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 65.0, 70.0, 69.0, 70.0, 70.0, 70.0, 70.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 62.0, 73.0, 63.0, 59.0, 54.0, 59.0, 68.0, 60.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 103.0, 89.0, 84.0, 82.0, 75.0, 87.0, 101.0, 78.0, 95.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 138.0, 145.0, 160.0, 164.0, 156.0, 163.0, 180.0, 160.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 191.0, 196.0, 180.0, 223.0, 181.0, 175.0, 167.0, 215.0], "value": 186.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 66.0, 68.0, 78.0, 84.0, 89.0, 70.0, 63.0], "value": 71.6, "unit": "score"}]}]} 18:25:05 INFO - Return code: 0 18:25:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:25:05 ERROR - # TBPL SUCCESS # 18:25:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:05 INFO - 2017-03-14 18:25:05.978 screenresolution[1926:16046] starting screenresolution argv=screenresolution get 18:25:05 INFO - 2017-03-14 18:25:05.981 screenresolution[1926:16046] Display 0: 1600x1200x32@60 18:25:05 INFO - 2017-03-14 18:25:05.987 screenresolution[1927:16049] starting screenresolution argv=screenresolution list 18:25:05 INFO - Available Modes on Display 0 18:25:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:06 INFO - Intel Iris: 18:25:06 INFO - Chipset Model: Intel Iris 18:25:06 INFO - Type: GPU 18:25:06 INFO - Bus: Built-In 18:25:06 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:06 INFO - Vendor: Intel (0x8086) 18:25:06 INFO - Device ID: 0x0a2e 18:25:06 INFO - Revision ID: 0x0009 18:25:06 INFO - Displays: 18:25:06 INFO - Display: 18:25:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:06 INFO - Display Serial Number: 1600x1200 60 18:25:06 INFO - Main Display: Yes 18:25:06 INFO - Mirror: Off 18:25:06 INFO - Online: Yes 18:25:06 INFO - Rotation: Supported 18:25:06 INFO - Return code: 0 18:25:06 INFO - Running post-action listener: _resource_record_post_action 18:25:06 INFO - [mozharness: 2017-03-15 01:25:06.227633Z] Finished run-tests step (success) 18:25:06 INFO - Running post-run listener: _resource_record_post_run 18:25:06 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 643072; Write bytes: 513656320; Read time: 11; Write time: 1624 18:25:06 INFO - TinderboxPrint: CPU usage
54.5% 18:25:06 INFO - TinderboxPrint: I/O read bytes / time
643,072 / 11 18:25:06 INFO - TinderboxPrint: I/O write bytes / time
513,656,320 / 1,624 18:25:06 INFO - TinderboxPrint: CPU idle
2,183.3 (45.5%) 18:25:06 INFO - TinderboxPrint: CPU system
964.8 (20.1%) 18:25:06 INFO - TinderboxPrint: CPU user
1,653.6 (34.4%) 18:25:06 INFO - TinderboxPrint: Swap in / out
209,600,512 / 0 18:25:06 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 209512960; Write bytes: 213379072; Read time: 16541; Write time: 479 18:25:06 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 479232; Write bytes: 298937856; Read time: 9; Write time: 1137 18:25:06 INFO - Running post-run listener: _upload_blobber_files 18:25:06 INFO - Blob upload gear active. 18:25:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:06 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 18:25:06 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:25:06 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:25:07 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:25:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:25:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:08 INFO - (blobuploader) - INFO - Done attempting. 18:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:25:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:09 INFO - (blobuploader) - INFO - Done attempting. 18:25:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:25:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:25:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:10 INFO - (blobuploader) - INFO - Done attempting. 18:25:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:25:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:25:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:12 INFO - (blobuploader) - INFO - Done attempting. 18:25:12 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:12 INFO - Return code: 0 18:25:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e54bbcf9d161e94745272331e6b4dc8007e1c6cfbe557955e4309ab4373ff616e4edab4def573805cf46cdf5db83db08665bec20bba3b5a81d427a7830150f43", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c41778538fc17c0cc2534dd091bbc401123dfcfe5e05a35e24048a0ea356cc1a2aa83c068781190029b061b5741419008a363d640c4530388e6a420f0fc688c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdca794c7d888fc8399b8d04f906a746030eb0eb59ffa96b48deed9aff3980ed1ee370995c30f77bf38f2f02e63cdc7b28dc060eb7ee5d5aa8c1329632e6a6e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20ab2997ac676a49a5c81da64c64b3a29d158542c98ec5385349bdbd952f01d165fc3be8be8c5c94684d954a7a98d4e044a8b062f27c990d92445e6a5c7622fd"} 18:25:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:12 INFO - Contents: 18:25:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e54bbcf9d161e94745272331e6b4dc8007e1c6cfbe557955e4309ab4373ff616e4edab4def573805cf46cdf5db83db08665bec20bba3b5a81d427a7830150f43", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c41778538fc17c0cc2534dd091bbc401123dfcfe5e05a35e24048a0ea356cc1a2aa83c068781190029b061b5741419008a363d640c4530388e6a420f0fc688c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdca794c7d888fc8399b8d04f906a746030eb0eb59ffa96b48deed9aff3980ed1ee370995c30f77bf38f2f02e63cdc7b28dc060eb7ee5d5aa8c1329632e6a6e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20ab2997ac676a49a5c81da64c64b3a29d158542c98ec5385349bdbd952f01d165fc3be8be8c5c94684d954a7a98d4e044a8b062f27c990d92445e6a5c7622fd"} 18:25:12 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:12 INFO - Copying logs to upload dir... 18:25:12 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.912858 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-14 18:25:12.621472) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 18:25:12.625265) ========= 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.3dDRbDki4r/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.NEYyZwBvZW/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e54bbcf9d161e94745272331e6b4dc8007e1c6cfbe557955e4309ab4373ff616e4edab4def573805cf46cdf5db83db08665bec20bba3b5a81d427a7830150f43", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c41778538fc17c0cc2534dd091bbc401123dfcfe5e05a35e24048a0ea356cc1a2aa83c068781190029b061b5741419008a363d640c4530388e6a420f0fc688c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdca794c7d888fc8399b8d04f906a746030eb0eb59ffa96b48deed9aff3980ed1ee370995c30f77bf38f2f02e63cdc7b28dc060eb7ee5d5aa8c1329632e6a6e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20ab2997ac676a49a5c81da64c64b3a29d158542c98ec5385349bdbd952f01d165fc3be8be8c5c94684d954a7a98d4e044a8b062f27c990d92445e6a5c7622fd"} build_url:https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011152 build_url: 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e54bbcf9d161e94745272331e6b4dc8007e1c6cfbe557955e4309ab4373ff616e4edab4def573805cf46cdf5db83db08665bec20bba3b5a81d427a7830150f43", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c41778538fc17c0cc2534dd091bbc401123dfcfe5e05a35e24048a0ea356cc1a2aa83c068781190029b061b5741419008a363d640c4530388e6a420f0fc688c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdca794c7d888fc8399b8d04f906a746030eb0eb59ffa96b48deed9aff3980ed1ee370995c30f77bf38f2f02e63cdc7b28dc060eb7ee5d5aa8c1329632e6a6e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20ab2997ac676a49a5c81da64c64b3a29d158542c98ec5385349bdbd952f01d165fc3be8be8c5c94684d954a7a98d4e044a8b062f27c990d92445e6a5c7622fd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 18:25:12.664812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:25:12.665608) ========= 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.3dDRbDki4r/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.NEYyZwBvZW/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.005259 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:25:12.715720) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 18:25:12.716057) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 18:25:12.716373) ========= ========= Total master_lag: 3.46 =========