builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0186 starttime: 1483890338.33 results: success (0) buildid: 20170108065456 builduid: 5504f822957a465a99ecaf2b3aa8858a revision: d192a99be4b436f2dc839435319f7630d5d8f4b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.333340) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.333728) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.334026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.360573) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.360918) ========= 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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-01-08 07:45:38-- 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% 15.1M=0.001s 2017-01-08 07:45:38 (15.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.470424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.855227) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.855498) ========= 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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.026387 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.905381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:38.905959) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d192a99be4b436f2dc839435319f7630d5d8f4b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d192a99be4b436f2dc839435319f7630d5d8f4b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-01-08 07:45:38,999 truncating revision to first 12 chars 2017-01-08 07:45:38,999 Setting DEBUG logging. 2017-01-08 07:45:38,999 attempt 1/10 2017-01-08 07:45:38,999 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d192a99be4b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-08 07:45:39,638 unpacking tar archive at: mozilla-central-d192a99be4b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912924 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:39.843068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:39.843367) ========= script_repo_revision: d192a99be4b436f2dc839435319f7630d5d8f4b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:39.843815) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:39.844093) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-08 07:45:39.864108) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-08 07:45:39.864446) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:45:40 INFO - MultiFileLogger online at 20170108 07:45:40 in /builds/slave/test 07:45:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:45:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:45:40 INFO - {'append_to_log': False, 07:45:40 INFO - 'base_work_dir': '/builds/slave/test', 07:45:40 INFO - 'blob_upload_branch': 'Firefox', 07:45:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:45:40 INFO - 'branch': 'Firefox', 07:45:40 INFO - 'buildbot_json_path': 'buildprops.json', 07:45:40 INFO - 'config_files': ('talos/mac_config.py',), 07:45:40 INFO - 'default_actions': ('clobber', 07:45:40 INFO - 'read-buildbot-config', 07:45:40 INFO - 'download-and-extract', 07:45:40 INFO - 'populate-webroot', 07:45:40 INFO - 'create-virtualenv', 07:45:40 INFO - 'install', 07:45:40 INFO - 'run-tests'), 07:45:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:45:40 INFO - 'download_minidump_stackwalk': True, 07:45:40 INFO - 'download_symbols': 'ondemand', 07:45:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:45:40 INFO - 'tooltool.py': '/tools/tooltool.py', 07:45:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:45:40 INFO - '/tools/misc-python/virtualenv.py')}, 07:45:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:45:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:45:40 INFO - 'installer_path': 'installer.exe', 07:45:40 INFO - 'log_level': 'info', 07:45:40 INFO - 'log_name': 'talos', 07:45:40 INFO - 'log_to_console': True, 07:45:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:45:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:45:40 INFO - 'opt_config_files': (), 07:45:40 INFO - 'pip_index': False, 07:45:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:45:40 INFO - 'cmd': ('bash', 07:45:40 INFO - '-c', 07:45:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:45:40 INFO - 'enabled': True, 07:45:40 INFO - 'halt_on_failure': False, 07:45:40 INFO - 'name': 'check_screen_resolution'},), 07:45:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:45:40 INFO - 'cmd': ('bash', 07:45:40 INFO - '-c', 07:45:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:45:40 INFO - 'enabled': True, 07:45:40 INFO - 'halt_on_failure': False, 07:45:40 INFO - 'name': 'check_screen_resolution'},), 07:45:40 INFO - 'run_cmd_checks_enabled': True, 07:45:40 INFO - 'sps_profile': False, 07:45:40 INFO - 'sps_profile_interval': 0, 07:45:40 INFO - 'suite': 'dromaeojs-e10s', 07:45:40 INFO - 'system_bits': '32', 07:45:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:45:40 INFO - 'title': 't-yosemite-r7-0186', 07:45:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:45:40 INFO - 'use_talos_json': True, 07:45:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:45:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:45:40 INFO - 'work_dir': 'build'} 07:45:40 INFO - [mozharness: 2017-01-08 15:45:40.072681Z] Running clobber step. 07:45:40 INFO - Running pre-action listener: _resource_record_pre_action 07:45:40 INFO - Running main action method: clobber 07:45:40 INFO - rmtree: /builds/slave/test/build 07:45:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:45:42 INFO - Running post-action listener: _resource_record_post_action 07:45:42 INFO - [mozharness: 2017-01-08 15:45:42.579986Z] Finished clobber step (success) 07:45:42 INFO - [mozharness: 2017-01-08 15:45:42.580234Z] Running read-buildbot-config step. 07:45:42 INFO - Running pre-action listener: _resource_record_pre_action 07:45:42 INFO - Running main action method: read_buildbot_config 07:45:42 INFO - Using buildbot properties: 07:45:42 INFO - { 07:45:42 INFO - "project": "", 07:45:42 INFO - "product": "firefox", 07:45:42 INFO - "script_repo_revision": "production", 07:45:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:45:42 INFO - "repository": "", 07:45:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:45:42 INFO - "buildid": "20170108065456", 07:45:42 INFO - "pgo_build": "False", 07:45:42 INFO - "basedir": "/builds/slave/test", 07:45:42 INFO - "buildnumber": 40, 07:45:42 INFO - "slavename": "t-yosemite-r7-0186", 07:45:42 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:45:42 INFO - "platform": "yosemite_r7", 07:45:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:45:42 INFO - "branch": "mozilla-central", 07:45:42 INFO - "repo_path": "mozilla-central", 07:45:42 INFO - "revision": "d192a99be4b436f2dc839435319f7630d5d8f4b0", 07:45:42 INFO - "stage_platform": "macosx64", 07:45:42 INFO - "builduid": "5504f822957a465a99ecaf2b3aa8858a", 07:45:42 INFO - "slavebuilddir": "test" 07:45:42 INFO - } 07:45:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:45:42 INFO - Running post-action listener: _resource_record_post_action 07:45:42 INFO - [mozharness: 2017-01-08 15:45:42.585779Z] Finished read-buildbot-config step (success) 07:45:42 INFO - [mozharness: 2017-01-08 15:45:42.585987Z] Running download-and-extract step. 07:45:42 INFO - Running pre-action listener: _resource_record_pre_action 07:45:42 INFO - Running main action method: download_and_extract 07:45:42 INFO - mkdir: /builds/slave/test/build/tests 07:45:42 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')]} 07:45:42 INFO - trying https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:45:42 INFO - Downloading https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:45:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:45:43 INFO - Downloaded 1199 bytes. 07:45:43 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:45:43 INFO - Using the following test package requirements: 07:45:43 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:45:43 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:45:43 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:45:43 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'jsshell-mac.zip'], 07:45:43 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:45:43 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:45:43 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:45:43 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:45:43 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:45:43 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:45:43 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:45:43 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:45:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:45:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:45:43 INFO - Fetch https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:45:44 INFO - Expected file size: 16304782 07:45:44 INFO - Obtained file size: 16304782 07:45:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:45:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:45:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:45:51 INFO - Fetch https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:45:52 INFO - Expected file size: 16304782 07:45:52 INFO - Obtained file size: 16304782 07:45:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:45:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:45:58 INFO - Fetch https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:45:59 INFO - Expected file size: 14403537 07:45:59 INFO - Obtained file size: 14403537 07:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:00 INFO - trying https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:46:00 INFO - Downloading https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:46:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:46:02 INFO - Downloaded 69763217 bytes. 07:46:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:46:02 INFO - mkdir: /builds/slave/test/properties 07:46:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:46:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:46:02 INFO - Contents: 07:46:02 INFO - build_url:https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:46:02 INFO - Running post-action listener: _resource_record_post_action 07:46:03 INFO - Running post-action listener: set_extra_try_arguments 07:46:03 INFO - [mozharness: 2017-01-08 15:46:03.000358Z] Finished download-and-extract step (success) 07:46:03 INFO - [mozharness: 2017-01-08 15:46:03.000492Z] Running populate-webroot step. 07:46:03 INFO - Running pre-action listener: _resource_record_pre_action 07:46:03 INFO - Running main action method: populate_webroot 07:46:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:46:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:46:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:46:03 INFO - '--tppagecycles', 07:46:03 INFO - '7'], 07:46:03 INFO - 'tests': ['tsvgm']}}, 07:46:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:46:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:46:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:46:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:46:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:46:03 INFO - 'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:46:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:46:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:46:03 INFO - 'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['damp', 'tps']}, 07:46:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:46:03 INFO - 'tests': ['damp', 'tps']}, 07:46:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['dromaeo_dom']}, 07:46:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:46:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['basic_compositor_video']}, 07:46:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:46:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['a11yr', 07:46:03 INFO - 'ts_paint', 07:46:03 INFO - 'tpaint', 07:46:03 INFO - 'sessionrestore', 07:46:03 INFO - 'sessionrestore_no_auto_restore', 07:46:03 INFO - 'tabpaint']}, 07:46:03 INFO - 'other-e10s': {'tests': ['a11yr', 07:46:03 INFO - 'ts_paint', 07:46:03 INFO - 'tpaint', 07:46:03 INFO - 'sessionrestore', 07:46:03 INFO - 'sessionrestore_no_auto_restore', 07:46:03 INFO - 'tabpaint']}, 07:46:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:46:03 INFO - 'ts_paint', 07:46:03 INFO - 'tpaint', 07:46:03 INFO - 'sessionrestore', 07:46:03 INFO - 'sessionrestore_no_auto_restore', 07:46:03 INFO - 'tabpaint']}, 07:46:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:46:03 INFO - 'ts_paint', 07:46:03 INFO - 'tpaint', 07:46:03 INFO - 'sessionrestore', 07:46:03 INFO - 'sessionrestore_no_auto_restore', 07:46:03 INFO - 'tabpaint']}, 07:46:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['a11yr', 07:46:03 INFO - 'ts_paint', 07:46:03 INFO - 'tpaint', 07:46:03 INFO - 'sessionrestore', 07:46:03 INFO - 'sessionrestore_no_auto_restore', 07:46:03 INFO - 'tabpaint']}, 07:46:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['a11yr', 07:46:03 INFO - 'ts_paint', 07:46:03 INFO - 'tpaint', 07:46:03 INFO - 'sessionrestore', 07:46:03 INFO - 'sessionrestore_no_auto_restore', 07:46:03 INFO - 'tabpaint']}, 07:46:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['tsvgx', 07:46:03 INFO - 'tsvgr_opacity', 07:46:03 INFO - 'tart', 07:46:03 INFO - 'tscrollx', 07:46:03 INFO - 'cart', 07:46:03 INFO - 'tsvg_static']}, 07:46:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:46:03 INFO - 'tsvgr_opacity', 07:46:03 INFO - 'tart', 07:46:03 INFO - 'tscrollx', 07:46:03 INFO - 'cart', 07:46:03 INFO - 'tsvg_static']}, 07:46:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:46:03 INFO - 'talos_options': ['--disable-e10s'], 07:46:03 INFO - 'tests': ['tp5o']}, 07:46:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:46:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:46:03 INFO - 'talos_options': ['--disable-e10s', 07:46:03 INFO - '--xperf_path', 07:46:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:03 INFO - 'tests': ['tp5n']}, 07:46:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:46:03 INFO - 'talos_options': ['--xperf_path', 07:46:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:46:03 INFO - 'tests': ['tp5n']}}, 07:46:03 INFO - 'talos.zip': {'path': '', 07:46:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:46:03 INFO - Running post-action listener: _resource_record_post_action 07:46:03 INFO - [mozharness: 2017-01-08 15:46:03.019110Z] Finished populate-webroot step (success) 07:46:03 INFO - [mozharness: 2017-01-08 15:46:03.019248Z] Running create-virtualenv step. 07:46:03 INFO - Running pre-action listener: _resource_record_pre_action 07:46:03 INFO - Running main action method: create_virtualenv 07:46:03 INFO - Creating virtualenv /builds/slave/test/build/venv 07:46:03 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 07:46:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:46:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:46:03 INFO - Using real prefix '/tools/python27' 07:46:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:46:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:46:06 INFO - Installing pip.................done. 07:46:06 INFO - Return code: 0 07:46:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:46:07 INFO - Reading from file tmpfile_stdout 07:46:07 INFO - Output received: 07:46:07 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) 07:46:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:46:07 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')]} 07:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:07 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')]} 07:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:07 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:07 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 07:46:07 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 07:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:07 INFO - 'HOME': '/Users/cltbld', 07:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:07 INFO - 'LOGNAME': 'cltbld', 07:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:07 INFO - 'MOZ_NO_REMOTE': '1', 07:46:07 INFO - 'NO_EM_RESTART': '1', 07:46:07 INFO - 'PAGER': '/bin/cat', 07:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:07 INFO - 'PWD': '/builds/slave/test', 07:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:07 INFO - 'SHELL': '/bin/bash', 07:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:07 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:07 INFO - 'USER': 'cltbld', 07:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:07 INFO - 'XPC_FLAGS': '0x0', 07:46:07 INFO - 'XPC_SERVICE_NAME': '0', 07:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:46:07 INFO - Cleaning up... 07:46:07 INFO - Return code: 0 07:46:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:46:07 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')]} 07:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:07 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')]} 07:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:07 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:07 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 07:46:07 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 07:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:07 INFO - 'HOME': '/Users/cltbld', 07:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:07 INFO - 'LOGNAME': 'cltbld', 07:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:07 INFO - 'MOZ_NO_REMOTE': '1', 07:46:07 INFO - 'NO_EM_RESTART': '1', 07:46:07 INFO - 'PAGER': '/bin/cat', 07:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:07 INFO - 'PWD': '/builds/slave/test', 07:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:07 INFO - 'SHELL': '/bin/bash', 07:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:07 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:07 INFO - 'USER': 'cltbld', 07:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:07 INFO - 'XPC_FLAGS': '0x0', 07:46:07 INFO - 'XPC_SERVICE_NAME': '0', 07:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:07 INFO - Downloading/unpacking psutil>=3.1.1 07:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:46:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:11 INFO - Installing collected packages: psutil 07:46:11 INFO - Running setup.py install for psutil 07:46:11 INFO - building 'psutil._psutil_osx' extension 07:46:11 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 07:46:12 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 07:46:12 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 07:46:12 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 07:46:12 INFO - building 'psutil._psutil_posix' extension 07:46:12 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 07:46:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:46:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:46:12 INFO - ^ 07:46:12 INFO - 1 warning generated. 07:46:12 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 07:46:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:46:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:46:12 INFO - Successfully installed psutil 07:46:12 INFO - Cleaning up... 07:46:12 INFO - Return code: 0 07:46:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:46:12 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')]} 07:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:12 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')]} 07:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:12 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:12 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 07:46:12 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 07:46:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:13 INFO - 'HOME': '/Users/cltbld', 07:46:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:13 INFO - 'LOGNAME': 'cltbld', 07:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:13 INFO - 'MOZ_NO_REMOTE': '1', 07:46:13 INFO - 'NO_EM_RESTART': '1', 07:46:13 INFO - 'PAGER': '/bin/cat', 07:46:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:13 INFO - 'PWD': '/builds/slave/test', 07:46:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:13 INFO - 'SHELL': '/bin/bash', 07:46:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:13 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:13 INFO - 'USER': 'cltbld', 07:46:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:13 INFO - 'XPC_FLAGS': '0x0', 07:46:13 INFO - 'XPC_SERVICE_NAME': '0', 07:46:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:46:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:46:15 INFO - Installing collected packages: mozsystemmonitor 07:46:15 INFO - Running setup.py install for mozsystemmonitor 07:46:16 INFO - Successfully installed mozsystemmonitor 07:46:16 INFO - Cleaning up... 07:46:16 INFO - Return code: 0 07:46:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:46:16 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')]} 07:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:16 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')]} 07:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:16 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:16 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 07:46:16 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 07:46:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:16 INFO - 'HOME': '/Users/cltbld', 07:46:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:16 INFO - 'LOGNAME': 'cltbld', 07:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:16 INFO - 'MOZ_NO_REMOTE': '1', 07:46:16 INFO - 'NO_EM_RESTART': '1', 07:46:16 INFO - 'PAGER': '/bin/cat', 07:46:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:16 INFO - 'PWD': '/builds/slave/test', 07:46:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:16 INFO - 'SHELL': '/bin/bash', 07:46:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:16 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:16 INFO - 'USER': 'cltbld', 07:46:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:16 INFO - 'XPC_FLAGS': '0x0', 07:46:16 INFO - 'XPC_SERVICE_NAME': '0', 07:46:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:46:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:19 INFO - Downloading functools32-3.2.3-2.tar.gz 07:46:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:46:19 INFO - warning: no files found matching '*.txt' 07:46:19 INFO - no previously-included directories found matching 'build' 07:46:19 INFO - no previously-included directories found matching 'dist' 07:46:19 INFO - no previously-included directories found matching '.git*' 07:46:19 INFO - Installing collected packages: jsonschema, functools32 07:46:19 INFO - Running setup.py install for functools32 07:46:19 INFO - warning: no files found matching '*.txt' 07:46:19 INFO - no previously-included directories found matching 'build' 07:46:19 INFO - no previously-included directories found matching 'dist' 07:46:19 INFO - no previously-included directories found matching '.git*' 07:46:19 INFO - Successfully installed jsonschema functools32 07:46:19 INFO - Cleaning up... 07:46:19 INFO - Return code: 0 07:46:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:46:19 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')]} 07:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:19 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')]} 07:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:19 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:19 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 07:46:19 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 07:46:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:19 INFO - 'HOME': '/Users/cltbld', 07:46:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:19 INFO - 'LOGNAME': 'cltbld', 07:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:19 INFO - 'MOZ_NO_REMOTE': '1', 07:46:19 INFO - 'NO_EM_RESTART': '1', 07:46:19 INFO - 'PAGER': '/bin/cat', 07:46:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:19 INFO - 'PWD': '/builds/slave/test', 07:46:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:19 INFO - 'SHELL': '/bin/bash', 07:46:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:19 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:19 INFO - 'USER': 'cltbld', 07:46:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:19 INFO - 'XPC_FLAGS': '0x0', 07:46:19 INFO - 'XPC_SERVICE_NAME': '0', 07:46:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:46:20 INFO - Cleaning up... 07:46:20 INFO - Return code: 0 07:46:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:46:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:46:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:20 INFO - 'HOME': '/Users/cltbld', 07:46:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:20 INFO - 'LOGNAME': 'cltbld', 07:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:20 INFO - 'MOZ_NO_REMOTE': '1', 07:46:20 INFO - 'NO_EM_RESTART': '1', 07:46:20 INFO - 'PAGER': '/bin/cat', 07:46:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:20 INFO - 'PWD': '/builds/slave/test', 07:46:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:20 INFO - 'SHELL': '/bin/bash', 07:46:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:20 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:20 INFO - 'USER': 'cltbld', 07:46:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:20 INFO - 'XPC_FLAGS': '0x0', 07:46:20 INFO - 'XPC_SERVICE_NAME': '0', 07:46:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:20 INFO - Downloading/unpacking blobuploader==1.2.4 07:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - Downloading blobuploader-1.2.4.tar.gz 07:46:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:46:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:46:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:24 INFO - Downloading docopt-0.6.1.tar.gz 07:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:46:24 INFO - Installing collected packages: blobuploader, requests, docopt 07:46:24 INFO - Running setup.py install for blobuploader 07:46:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:46:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:46:24 INFO - Running setup.py install for requests 07:46:25 INFO - Running setup.py install for docopt 07:46:25 INFO - Successfully installed blobuploader requests docopt 07:46:25 INFO - Cleaning up... 07:46:25 INFO - Return code: 0 07:46:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:25 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:25 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 07:46:25 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 07:46:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:25 INFO - 'HOME': '/Users/cltbld', 07:46:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:25 INFO - 'LOGNAME': 'cltbld', 07:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:25 INFO - 'MOZ_NO_REMOTE': '1', 07:46:25 INFO - 'NO_EM_RESTART': '1', 07:46:25 INFO - 'PAGER': '/bin/cat', 07:46:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:25 INFO - 'PWD': '/builds/slave/test', 07:46:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:25 INFO - 'SHELL': '/bin/bash', 07:46:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:25 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:25 INFO - 'USER': 'cltbld', 07:46:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:25 INFO - 'XPC_FLAGS': '0x0', 07:46:25 INFO - 'XPC_SERVICE_NAME': '0', 07:46:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:46:25 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-WeIGiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:46:25 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-cAYk6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:46:25 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-2Rq1xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:46:25 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-fIIIxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:46:25 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-swJ3_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Iw12Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-fUyKm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-CHEJHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-gTXWrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-WYzF2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-akZTRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-hUZ9Mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-twhmZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-5NoHMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:26 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-TJX2QF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:46:27 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-9q5iH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:46:27 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-8NI0a2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:46:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:46:27 INFO - Running setup.py install for manifestparser 07:46:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:46:27 INFO - Running setup.py install for mozcrash 07:46:27 INFO - Running setup.py install for mozdebug 07:46:27 INFO - Running setup.py install for mozdevice 07:46:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:46:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:46:27 INFO - Running setup.py install for mozfile 07:46:28 INFO - Running setup.py install for mozhttpd 07:46:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Running setup.py install for mozinfo 07:46:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Running setup.py install for mozInstall 07:46:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Running setup.py install for mozleak 07:46:28 INFO - Running setup.py install for mozlog 07:46:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Running setup.py install for moznetwork 07:46:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:46:28 INFO - Running setup.py install for mozprocess 07:46:29 INFO - Running setup.py install for mozprofile 07:46:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:46:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:46:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:46:29 INFO - Running setup.py install for mozrunner 07:46:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:46:29 INFO - Running setup.py install for mozscreenshot 07:46:29 INFO - Running setup.py install for moztest 07:46:29 INFO - Running setup.py install for mozversion 07:46:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:46:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:46:29 INFO - Cleaning up... 07:46:29 INFO - Return code: 0 07:46:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:46: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')]} 07:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46: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')]} 07:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:29 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:29 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 07:46:29 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 07:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:29 INFO - 'HOME': '/Users/cltbld', 07:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:29 INFO - 'LOGNAME': 'cltbld', 07:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:29 INFO - 'MOZ_NO_REMOTE': '1', 07:46:29 INFO - 'NO_EM_RESTART': '1', 07:46:29 INFO - 'PAGER': '/bin/cat', 07:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:29 INFO - 'PWD': '/builds/slave/test', 07:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:29 INFO - 'SHELL': '/bin/bash', 07:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:29 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:29 INFO - 'USER': 'cltbld', 07:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:29 INFO - 'XPC_FLAGS': '0x0', 07:46:29 INFO - 'XPC_SERVICE_NAME': '0', 07:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Byw8ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-d8Rh1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-v0smog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-7le5x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-71htrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-sbF1Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-If3Jjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-J3_mm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:46:30 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)) 07:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:46:30 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-9AcDuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:46:31 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-gNHDMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:46:31 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-2M0BJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:46:31 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-ssZRWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-VT0p1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:46:31 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-YnpAva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:46:31 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-5dcxdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:46:31 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-ZDi_Mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:46:31 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)) 07:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:46:31 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-xjAmYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:46:31 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)) 07:46:31 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)) 07:46:31 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)) 07:46:31 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)) 07:46:31 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)) 07:46:31 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)) 07:46:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:46:34 INFO - Downloading blessings-1.6.tar.gz 07:46:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:46:34 INFO - Installing collected packages: blessings 07:46:34 INFO - Running setup.py install for blessings 07:46:34 INFO - Successfully installed blessings 07:46:34 INFO - Cleaning up... 07:46:34 INFO - Return code: 0 07:46:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:46:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:46:35 INFO - Reading from file tmpfile_stdout 07:46:35 INFO - Current package versions: 07:46:35 INFO - blessings == 1.6 07:46:35 INFO - blobuploader == 1.2.4 07:46:35 INFO - docopt == 0.6.1 07:46:35 INFO - functools32 == 3.2.3-2 07:46:35 INFO - jsonschema == 2.5.1 07:46:35 INFO - manifestparser == 1.1 07:46:35 INFO - mozInstall == 1.12 07:46:35 INFO - mozcrash == 1.0 07:46:35 INFO - mozdebug == 0.1 07:46:35 INFO - mozdevice == 0.49 07:46:35 INFO - mozfile == 1.2 07:46:35 INFO - mozhttpd == 0.7 07:46:35 INFO - mozinfo == 0.9 07:46:35 INFO - mozleak == 0.1 07:46:35 INFO - mozlog == 3.4 07:46:35 INFO - moznetwork == 0.27 07:46:35 INFO - mozprocess == 0.24 07:46:35 INFO - mozprofile == 0.28 07:46:35 INFO - mozrunner == 6.13 07:46:35 INFO - mozscreenshot == 0.1 07:46:35 INFO - mozsystemmonitor == 0.3 07:46:35 INFO - moztest == 0.8 07:46:35 INFO - mozversion == 1.4 07:46:35 INFO - psutil == 3.1.1 07:46:35 INFO - requests == 1.2.3 07:46:35 INFO - wsgiref == 0.1.2 07:46:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:35 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:35 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 07:46:35 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 07:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:35 INFO - 'HOME': '/Users/cltbld', 07:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:35 INFO - 'LOGNAME': 'cltbld', 07:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:35 INFO - 'MOZ_NO_REMOTE': '1', 07:46:35 INFO - 'NO_EM_RESTART': '1', 07:46:35 INFO - 'PAGER': '/bin/cat', 07:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:35 INFO - 'PWD': '/builds/slave/test', 07:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:35 INFO - 'SHELL': '/bin/bash', 07:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:35 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:35 INFO - 'USER': 'cltbld', 07:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:35 INFO - 'XPC_FLAGS': '0x0', 07:46:35 INFO - 'XPC_SERVICE_NAME': '0', 07:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 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)) 07:46:35 INFO - Cleaning up... 07:46:35 INFO - Return code: 0 07:46:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:46:35 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 0x107280b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107283768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4925cc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, '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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 07:46:35 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 07:46:35 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 07:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:35 INFO - 'HOME': '/Users/cltbld', 07:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:35 INFO - 'LOGNAME': 'cltbld', 07:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:35 INFO - 'MOZ_NO_REMOTE': '1', 07:46:35 INFO - 'NO_EM_RESTART': '1', 07:46:35 INFO - 'PAGER': '/bin/cat', 07:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:35 INFO - 'PWD': '/builds/slave/test', 07:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:35 INFO - 'SHELL': '/bin/bash', 07:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:35 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:35 INFO - 'USER': 'cltbld', 07:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:35 INFO - 'XPC_FLAGS': '0x0', 07:46:35 INFO - 'XPC_SERVICE_NAME': '0', 07:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:46:35 INFO - Cleaning up... 07:46:35 INFO - Return code: 0 07:46:35 INFO - Running post-action listener: _resource_record_post_action 07:46:35 INFO - Running post-action listener: _start_resource_monitoring 07:46:35 INFO - Starting resource monitoring. 07:46:35 INFO - [mozharness: 2017-01-08 15:46:35.778290Z] Finished create-virtualenv step (success) 07:46:35 INFO - [mozharness: 2017-01-08 15:46:35.779210Z] Running install step. 07:46:35 INFO - Running pre-action listener: _resource_record_pre_action 07:46:35 INFO - Running main action method: install 07:46:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:46:36 INFO - Reading from file tmpfile_stdout 07:46:36 INFO - Detecting whether we're running mozinstall >=1.0... 07:46:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:46:36 INFO - Reading from file tmpfile_stdout 07:46:36 INFO - Output received: 07:46:36 INFO - Usage: mozinstall [options] installer 07:46:36 INFO - Options: 07:46:36 INFO - -h, --help show this help message and exit 07:46:36 INFO - -d DEST, --destination=DEST 07:46:36 INFO - Directory to install application into. [default: 07:46:36 INFO - "/builds/slave/test"] 07:46:36 INFO - --app=APP Application being installed. [default: firefox] 07:46:36 INFO - mkdir: /builds/slave/test/build/application 07:46:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:46:58 INFO - Reading from file tmpfile_stdout 07:46:58 INFO - Output received: 07:46:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:46:58 INFO - Running post-action listener: _resource_record_post_action 07:46:58 INFO - [mozharness: 2017-01-08 15:46:58.041066Z] Finished install step (success) 07:46:58 INFO - [mozharness: 2017-01-08 15:46:58.041229Z] Running run-tests step. 07:46:58 INFO - Running pre-action listener: _resource_record_pre_action 07:46:58 INFO - Running main action method: run_tests 07:46:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:46:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:46:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:46:58 INFO - 2017-01-08 07:46:58.055 screenresolution[1629:8238] starting screenresolution argv=screenresolution get 07:46:58 INFO - 2017-01-08 07:46:58.058 screenresolution[1629:8238] Display 0: 1600x1200x32@60 07:46:58 INFO - 2017-01-08 07:46:58.064 screenresolution[1630:8242] starting screenresolution argv=screenresolution list 07:46:58 INFO - Available Modes on Display 0 07:46:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:46:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:46:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:46:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:46:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:46:58 INFO - Intel Iris: 07:46:58 INFO - Chipset Model: Intel Iris 07:46:58 INFO - Type: GPU 07:46:58 INFO - Bus: Built-In 07:46:58 INFO - VRAM (Dynamic, Max): 1536 MB 07:46:58 INFO - Vendor: Intel (0x8086) 07:46:58 INFO - Device ID: 0x0a2e 07:46:58 INFO - Revision ID: 0x0009 07:46:58 INFO - Displays: 07:46:58 INFO - Display: 07:46:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:46:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:46:58 INFO - Display Serial Number: 1600x1200 60 07:46:58 INFO - Main Display: Yes 07:46:58 INFO - Mirror: Off 07:46:58 INFO - Online: Yes 07:46:58 INFO - Rotation: Supported 07:46:58 INFO - Return code: 0 07:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:46:58 INFO - Python 2.7.3 07:46:58 INFO - Return code: 0 07:46:58 INFO - grabbing minidump binary from tooltool 07:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:46:58 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 0x10705ed30>, '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 0x106f85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107053be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:46:58 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 07:46:58 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 07:46:58 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 07:46:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:46:58 INFO - Return code: 0 07:46:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:46:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:46:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:46:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:46:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 07:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0186 --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 07:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hwbuqrne4x/Render', 07:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:46:58 INFO - 'HOME': '/Users/cltbld', 07:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:46:58 INFO - 'LOGNAME': 'cltbld', 07:46:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:46:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:46:58 INFO - 'MOZ_NO_REMOTE': '1', 07:46:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:46:58 INFO - 'NO_EM_RESTART': '1', 07:46:58 INFO - 'PAGER': '/bin/cat', 07:46:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:46:58 INFO - 'PWD': '/builds/slave/test', 07:46:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:46:58 INFO - 'SHELL': '/bin/bash', 07:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZIUOWtoOPF/Listeners', 07:46:58 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 07:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:46:58 INFO - 'USER': 'cltbld', 07:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:46:58 INFO - 'XPC_FLAGS': '0x0', 07:46:58 INFO - 'XPC_SERVICE_NAME': '0', 07:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:46:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 07:46:58 INFO - mozversion application_buildid: 20170108065456 07:46:58 INFO - mozversion application_changeset: d192a99be4b436f2dc839435319f7630d5d8f4b0 07:46:58 INFO - mozversion application_display_name: Nightly 07:46:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:46:58 INFO - mozversion application_name: Firefox 07:46:58 INFO - mozversion application_remotingname: firefox 07:46:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:46:58 INFO - mozversion application_vendor: Mozilla 07:46:58 INFO - mozversion application_version: 53.0a1 07:46:58 INFO - mozversion platform_buildid: 20170108065456 07:46:58 INFO - mozversion platform_changeset: d192a99be4b436f2dc839435319f7630d5d8f4b0 07:46:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:46:58 INFO - mozversion platform_version: 53.0a1 07:46:58 INFO - using testdate: 1483890418 07:46:58 INFO - actual date: 1483890418 07:46:58 INFO - starting webserver on 'localhost:49246' 07:46:58 INFO - SUITE-START | Running 2 tests 07:46:58 INFO - TEST-START | dromaeo_css 07:46:58 INFO - operating with platform_type : mac_ 07:46:58 INFO - Initialising browser for dromaeo_css test... 07:46:58 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpw4S4fN/profile http://localhost:49246/getInfo.html) 07:46:59 INFO - PROCESS | 1640 | 1483890419208 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 07:47:01 INFO - PROCESS | 1640 | 2017-01-08 07:47:01.531 plugin-container[1642:8411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 07:47:01 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:47:01 INFO - PROCESS | 1640 | 2017-01-08 07:47:01.533 plugin-container[1642:8411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0b, name = 'com.apple.CFPasteboardClient' 07:47:01 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:47:01 INFO - PROCESS | 1640 | 2017-01-08 07:47:01.533 plugin-container[1642:8411] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:47:01 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 07:47:01 INFO - PROCESS | 1640 | colorDepth:24 07:47:01 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 07:47:01 INFO - PROCESS | 1640 | __metrics 07:47:01 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 07:47:02 INFO - TEST-INFO | 1640: exit 0 07:47:02 INFO - Browser initialized. 07:47:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:47:02 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpw4S4fN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:47:04 INFO - PROCESS | 1644 | 2017-01-08 07:47:04.725 plugin-container[1645:8538] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 07:47:04 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:47:04 INFO - PROCESS | 1644 | 2017-01-08 07:47:04.725 plugin-container[1645:8538] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 07:47:04 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:47:04 INFO - PROCESS | 1644 | 2017-01-08 07:47:04.725 plugin-container[1645:8538] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:47:14 INFO - PROCESS | 1644 | RSS: Main: 232833024 07:47:14 INFO - PROCESS | 1644 | 07:50:18 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 07:50:20 INFO - PROCESS | 1644 | RSS: Main: 249876480 07:50:20 INFO - PROCESS | 1644 | 07:52:02 INFO - PROCESS | 1644 | ************************* 07:52:02 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 07:52:02 INFO - PROCESS | 1644 | 07:52:02 INFO - PROCESS | 1644 | Full message: ReferenceError: fetch is not defined 07:52:02 INFO - PROCESS | 1644 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:52:02 INFO - PROCESS | 1644 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:52:02 INFO - PROCESS | 1644 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:52:02 INFO - PROCESS | 1644 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:52:02 INFO - PROCESS | 1644 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:52:02 INFO - PROCESS | 1644 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:52:02 INFO - PROCESS | 1644 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:52:02 INFO - PROCESS | 1644 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:52:02 INFO - PROCESS | 1644 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:52:02 INFO - PROCESS | 1644 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:52:02 INFO - PROCESS | 1644 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:52:02 INFO - PROCESS | 1644 | 07:52:02 INFO - PROCESS | 1644 | ************************* 07:53:22 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 07:53:23 INFO - PROCESS | 1644 | RSS: Main: 261124096 07:53:23 INFO - PROCESS | 1644 | 07:56:25 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 07:56:25 INFO - PROCESS | 1644 | RSS: Main: 269942784 07:56:25 INFO - PROCESS | 1644 | 07:59:27 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 07:59:28 INFO - PROCESS | 1644 | RSS: Main: 266698752 07:59:28 INFO - PROCESS | 1644 | 08:02:31 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 08:02:32 INFO - PROCESS | 1644 | RSS: Main: 271167488 08:02:32 INFO - PROCESS | 1644 | 08:05:34 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 08:05:35 INFO - PROCESS | 1644 | RSS: Main: 273694720 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | __start_tp_report 08:05:35 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 08:05:35 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 08:05:35 INFO - PROCESS | 1644 | |i|pagename|runs| 08:05:35 INFO - PROCESS | 1644 | |0;dojo.html;3334.5665063541737;3358.4626459766437;3363.4786608075756;3386.238096428304;3387.4749413840855;5474.562035037197;5485.533729632981;5503.862403439916;5512.614117011811;5525.613207075507;5442.156465747806;5494.532964697995;5536.335639723229;5572.749226284816;5581.888362232763;5077.162268225738;5118;5142.151544995071;5165.225216217578;5180.2488639147305;5056.595472362221;5112;5157.587393008569;5170.53465188133;5176.275321454999;4127.917441651165;4454.774936892348;5155;5185.922211166836;5203.063448579254;5041.117804384255;5078.669886457368;5167.948320516822;5169.53474187325;5217;4995.375578052744;5025.522575355335;5038.748062596856;5102.770375333082;5137.306463627438;5275.971185618806;5457.5633949283965;5471.863203419906;5514.972425137848;5532.695701736403;6083.30042045166;6128.172696685935;6133.325334213233;6155.784547540859;6157.230346206724;6055.152278680988;6098.817035488943;6116;6116.153172518083;6144.893919094561;5713.571482138815;5766.625169363978;5773.624714393551;5789.710514474302;5812.447817457335;6264.843378915563;6267.2792628848265;6270.467010304084;6280.152179455807;6284;5889.793857214977;5943.257092863392;5945.1973983512535;6106.297775755845;6137.938620613826;4603.355530225704;5415.889742602757;5481.835544933659;6121.757838957688;6144.754209831646;4590.724556526619;4760.952390476051;6001;6020.307664618625;6173.9073913891325;5555.416681248411;5629.802956896489;6034.969825150846;6088.178095957078;6167.105769663373;4960.355153829979;4986.252062190628;4987.451380348159;4988.301637770715;4990.451050384455;4369.148016136823;4375.365571992043;4386.561343865588;4387.934180987288;4446.888827779331;5758.280214973129;5772.855678607984;5820.970895145582;5854.092615644582;5879.853003674943;5797.913031304533;6148.354422785633;6155.230596175478;6181.876362472725;6184.072389141575;5662.915056274159;5705.22979397776;5707.258056452634;5786.103154011135;5836.095405212114;5291.867703307371;5359.544438722674;5359.651622644516;5366.543843773245;5419.783208671609;6273.52948528853;6277.246730392382;6281.3404592518045;6288.434040936338;6304.71628776697;6031;6049.274087109575;6151.692415379123;6198.101275315144;6200.844978875564;6147.262328520606;6147.631142131487;6232.09634602971;6251.249850018027;6378.74485020594;6093.695315234132;6101.871153836554;6116.969415152896;6117.510599151969;6133.3866613338305;6036.6679832609625;6126.754929802758;6133.447989680772;6164.815055548341;6169.259688837368;5832.212651292021;5888.028475301528;5891.381404952418;5928.288605367384;5964.31410387811;5938.762449501972;5942.227510423617;5945.464908158287;5947.405259474191;5966.582339236385;5083.2883396323805;5517.779288828402;5758.510526605285;5785.595008349375;5828;5935.851603709942;6015.6691381974415;6018.157457955977;6020;6026.548008899263;5520.70263488088;5823.621464604787;5826.621269617462;5851.180834683233;5877.235959325431 08:05:35 INFO - PROCESS | 1644 | |1;ext.html;429.88178250980997;462.48432997208124;478.8443755779373;509.0938130128372;521.4889408379788;32900.67099329006;39112;41110.7666769997;41214.17571648565;43338;42164.78917605407;42399;42482;42544.361834572504;42654.786726066326;8468;9403.95298023509;9562;9564.90435095647;9574.13832755052;8555.828883422328;11201.271917325388;11489.885101148966;11502.539898404053;11761.823572646417;11527;11573.016293615054;11575.884241157606;11599.362035088066;11629.651110466657;11456.48445819936;11473.541058357656;11495.712607184803;11507.482163302671;11521;10821.83767243492;10999.285046472034;11365.374904380256;11425.257358271769;11439.027682646985;39928.600713993066;39945.60054399477;40097.79951100255;40155;40290.99272518211;3996.840126394941;4028.194361127757;4028.47629808126;4035.858744943913;4037.9596204037875;29750.851245743634;30227.0931872042;30573;30748.69251307503;30850.845745771352;2765.7234276572385;2766.2669392610974;3251.9349613007844;3267.3628642414737;3350.1959529712694;440237;444243;446865;448013.7599312015;448589;58428;58615;58926.410735892096;59046;59071.40928590745;61882.69058654768;62019.689901550206;62026.379736202965;62028;62168.756624868154;12316.753664926651;14315.997880148498;15870.52388428349;16078.196090195443;16080.758788618181;11207.887921120684;11499.195056345976;12359.38203089842;12511.624651260478;12577.87422125767;18137.189064518792;18521.166547505396;21842;21850.23524176678;22619.88690056539;8534.829303413897;8840.010997525505;8840.425372350777;8864.601092950832;8865.650602590318;810.8621534339059;863.2748491267365;867.5878957495265;879.2658130461009;908.3051465628799;27113.593296100575;28663.71336286652;29600.851995740315;29745;30441.69558304433;982.9950850245846;989.080155455419;1014.452195814252;1018.9643362482277;1208.4380762945273;11918.225315354473;12138.237731972018;15344.769828452581;15556.377744890304;15808.367665293259;629137;629138;633749;635969;636792;57885;58254;58651.70674146687;58701;58816.41183588195;1254.4292346982102;1417.2630232279364;1456.3155317000992;1477.09158867298;1479.9112053276838;28325.71674283231;28373.432531348844;28820.279493012844;29599.85200073986;29711;40566;47563;48943.02113957772;49228.60028196455;49444.258336124265;538805;540670;541802;542532;543056;34766.30467390689;35758.28483430265;40903.59096408998;41460.96347591334;42258.78870605627;41450;43968;46081.539184607725;46107.53892461032;46793.76603116962;38421.61578384292;38509;39107.2178556433;39272.214555709295;39683.60316396799;32426.189345266557;37905.81047094857;38163.61836381601;38927.80536097301;39375;37067.25865482729;37641;37758.81120594379;37759.244815104095;37834;29598.556021660122;31596.368072638877;33887.83056084704;33936.15159621029;34150.80472183462;21214.681779773626;21705.674414883473;22709.659355109357;22833;22940.197093101662 08:05:35 INFO - PROCESS | 1644 | |2;jquery.html;361.14949294411656;387.5852837463911;387.7781675045794;396.41924580489587;421.593162598093;13978.930105349458;16112.59718507035;20273.39179824606;20940;21010.949452527388;20401.693974590355;20870.89564552179;20992.790072099273;21672.566548669056;21790.128394864227;5877.441643043925;5935.545791281132;6007.7596896124105;6083.665398403086;6091;5009.749512524378;5982.61113027654;6099.390060993887;6106.328303886569;6109.969450152743;5924.911126333109;6065.211522502091;6066.636001839904;6070.453659170674;6075.939240607581;5492.505674489315;6059.818205453844;6061.878762424749;6064.363241859607;6382.393672601095;6089.512838972916;6099.420555047263;6113.694315284218;6124.785632502841;6126.632402055846;13497.865021349759;25152;25292.367690807692;25595;26344.47311053768;2559.091522509501;2600.7139214686367;2653.8009649276382;2663.9200823975116;2972.1677930179367;9309.30180236485;9872.111509964137;14164.787528187086;15109;15960.441384551601;1558.7895634089366;1745.95635109122;1843.8524918006526;1851.472330385847;1884.3969929622526;119554;129087;130029.34985325013;132468;133047;11499.3675347855;12024.458899349374;12069.879301207051;12082.516699332104;12192.146549741434;15517.922410388102;15558.533244002698;15562.610934726723;15567.766483502757;15583.688326233412;7199.568025918462;8718.65125394977;8833.205011548991;8907.331950103768;8984.416012959136;6850.623215723183;8602.698905538276;8650.307975362082;8750;8760.211580957744;4741.502142275081;4822.421309442819;4823.782929768169;4840.104580652591;4925.827596034193;2026.3009261804655;2068.43118142509;2072.9792702073087;2074.2740040985536;2079.074811708775;764.170874601053;862.9180227878342;872.438335379676;898.910108989109;916.8258030974093;20991.580168396547;21071.789282106984;21975.901204939688;21991.230306939182;22022.559548809255;699.964053201265;828.6271177969937;840.7430890818143;904.8914130304274;916.8533034714413;9296.535173241446;9859.654912078044;11625.314329422186;11666;11741.354225517507;622885.7114288535;700327;709827.4508627424;740916;746135;107580;116707;116803;123202;139237;980.6959842448864;1061.4798748613123;1072.940988245654;1073.8228192348174;1075.473018221066;20407.4898127548;20615;20687.482812929797;20712.96435178265;21025.789742102384;61232.08151877815;63235.6838215806;63765.681171593846;64461.35538644553;65431.34568654443;90437;92338;92886;94459;94571;8464.407491475538;8495.447795893248;8544.700935467228;8844;8935.687250946447;10956.34261944286;12861.67845803825;13699.863001369858;13869;13998.930005349908;11687.824682629598;11708.531658733555;11791.528338866348;12030.578929737418;12141.57504487374;11637.069034477052;11678.416079196177;11697.941510292394;11714.531418743496;11742.88257117418;9460;11726;11908.821367679318;12057.57798477049;12156.878431215575;8284.917150828414;8522.573871306535;8673.826523469621;8812.823743524965;8930.151635594608;8746.781330466789;11468.598599048993;11672.649820505398;11822.586209482626;12031.819522707341 08:05:35 INFO - PROCESS | 1644 | |3;mootools.html;1447.6380904773807;1465.8314293856197;1466.7726502392127;1474.0787008119926;1493.4175671488122;7505.324520793128;7754.302112809846;8008.719694810676;8412.36907231957;8448.957755211231;6645.966770166143;7887;8189.8362032759305;8302.958485207566;8310.501369917809;2315.9652605210936;2351.905923763047;2359.103540654549;2359.4219416243045;2367.7277113132036;2341.309313752439;2369.3247424484025;2370.0875163062174;2370.0993622423457;2380.4167978845207;2308.076769292288;2325.94185145371;2336.7663233676667;2339.9766002340016;2340.8361414700894;2298.9654655404966;2308.607536718762;2330.6387509935985;2330.848494847838;2335.556244313575;2269.716285464317;2301.953960920789;2307.746147923727;2311.4914718761847;2331.63859601762;7187.425005999507;8148.67405303793;8319.251267385904;8378.078411374743;8389.748307550783;5032.018756342514;5198.298229738974;5199.506046925536;5200.5059519345605;5208.427073021964;3356.580427446569;3378.3074469733647;3507.7018453431488;3573.3032058748554;3592.892213233607;1159.7506536094695;1182.9940850295693;1225.7497352700284;1227.858796238426;1246.4515613130193;10589.470526473646;10700.357978521313;10742.3017503862;10768;10778.622748203776;4354.129174165179;4633.745144017044;4646.83736069236;4693.084848454587;4709.128811169944;8305.46014509055;8341.45780524264;9338.299627527962;9386.95306523463;9447.385919915183;5174.456682048406;5175.146100893386;5193.221016847428;5193.662411943212;5200.037992971313;3533.787972721645;3542.557180352456;3847.903347545936;4239.554846741109;4746.976265118652;3199.9200019999685;3767.9246415071543;3842.51968503937;3872.5546562145714;3915.4126880967515;1275.0691994843817;1275.8724127587168;1279.5305061231911;1290.316132449803;1294.2428679222571;868.1708967935606;895.892492900856;919.5402298850574;923.8995890478731;931.5854444772147;5578.721063946787;5594.412586678422;5741.942580574141;5761.798337058183;5900;571.7227144834752;582.4466756581264;593.2465768474013;594.0851089322396;596.1088173181122;2788.9581656275172;3667.156553992597;3691.667749902468;3791.9241615167543;3841.7310788245113;9675.758106047406;9845.458499782437;9932.453715045598;9954.203663706887;9954.402735835729;9844.458554779412;9918.801623967625;9919.256055795844;9921.50392480373;9979.800403992025;5733.799317023968;7357.117145942521;7756.53460792343;7858.17489163641;7886.960565197022;5485.204645326485;5588.329400471969;5603.271574695264;5609.523190528687;5616.634918730269;8685.956570217108;8709.695160669346;8813.25087367581;8834.116588341114;8858.424202426822;8809.823803524021;8860.335474839283;8903.599338029675;8917.554122293988;9061.275097992013;6520.282768895512;6701.128853249046;6783.253842077466;6981.965090174516;7026.227115017242;7652.693892244248;7779.844403111792;7814.843703125792;7815.648295826588;7863.977682901186;6979.511434199558;7111.82220444493;7144.607046612487;7150.499465037397;7237.601931893796;6882.483813713891;6918.930810691829;6952.443804495527;7134.714611415485;7262.273772622695;6299.559030867978;6402.35976402356;6520;6522.315156908457;6523.706433210612;4819.036192761532;4872.853814385574;5027.773750181177;5030;5155.175171972466;4946.678465899705;5080.593552515864;5120.3133743704475;5169.870753231199;5216.947830521646 08:05:35 INFO - PROCESS | 1644 | |4;prototype.html;295.7190668864578;330.40856866209486;365.2202547560958;369.10492056762354;372.1644907183372;4879.950031230481;5174.352081782344;5374.050073196203;5421.295231619889;5489.862753431166;4657.4876763555985;5303.628745987782;5329.147336426173;5347.839564813052;5393.83818485445;3632.4914511968345;3797.905052373699;3992.8003599819967;4094.5086589609286;4122.8969275768195;3487.686108250257;3956.0109972506875;4165.563674686449;4174.70777045607;4181.310083836163;4159.854405095823;4176.2900306947895;4176.331786914095;4185.497740271172;4197.7271477354025;3404.4042292598942;3697.7041836653;3698.500702405181;4040.252628212814;4141.730787498803;2835.8440285784272;3465.324261768956;3500.1949551603275;3516.2967406518796;3524.0661224775454;4303.956960430418;5177.48225177483;5259.631825772198;5292.470752924715;5298.443663415363;3907.628775266345;3935.60643935607;4627.699199552052;4652.092841895832;4687.015726697397;4557.954420455786;4820.927686084711;5488.25908502355;5552.02902969444;5702.914456283159;3836.0601652595014;3841.2509560635685;3850.0567360996456;3870.0262865938294;3882.456456096149;5412.296401467824;5561.833145005657;5644.673501727091;5749.252597162342;5916.437938395846;3569.910752231163;3686.336459437327;3883.514560679915;3899.395593682984;3916.74541154824;3905.902352441212;3934.9016274593364;3950.150717595731;3954.268460334847;3969.4244334571326;4500.324951257272;4541.772911354486;4605.677602567788;4638.7502061500145;4652.232381656989;5696;5711.114777209456;5723.341815691166;5772.509336706427;5861.824145275648;1143.4397145398696;1164.5050853387277;1267.5056727876135;1268.898488120948;1291.9935400323127;739.5320289225882;742.7957311739207;756.9053868266467;770.9768706938801;786.6460092958077;4365.581186114526;5051.419086804991;5085.64400491962;5101;5119.360079990001;4566.771661416916;5350.803169736698;5395.331174801095;5588.846276765747;5713.314402271754;4458.974435879766;4492.842750503717;4521.434820647419;4526.366308716782;4703.95296047042;2849.4782117825876;2885.5238885584063;2903.187107609873;2925.3564215872475;2933.853307334625;4999.850004499871;5043.798248070036;5276.498732620395;5833.970830145822;5889.322727886262;5152.793888244503;5206.854492011751;5258.605604579672;5739.282589676291;5769.269219234213;5147.6654017488745;5731.933415622622;5786.681732504669;5914.4381283777975;6041.885340131007;4720.811167553259;4731.787069581946;4798.648662155589;4839.758012099311;5125.539740924302;4714.905701885875;4949.050949050949;5584.46947539983;5793.391693872083;5803.593748437569;5621.409751975983;5654.095344744821;5672.943270567242;5821.565276486893;6032.0791485534755;3658.560972683295;3726.6646001859963;3896.6298201670797;3908.4332771747368;3938.2123575284486;5867.559933005127;5883.73476211653;5895.4399332063385;5898.351181370132;5950.404959504188;4997.475265097112;5093.83512007388;5152.381714194321;5184.766685499237;5705.518099626675;5254.395744489432;5600.803971860965;5606.915896261477;5609.074502707008;5644.350899646429;5322.866928326823;5390.601182473762;5490.917636235539;5645.633033852787;5650.350209725934;5453.482503785116;5630.352509461464;5643.887122257614;5673.914891276551;5674.971625141848;4720.575148236675;5140.791913900305;5550.5282051026115;5607.775688972559;5614.635048721983 08:05:35 INFO - PROCESS | 1644 | |5;yui.html;693.7745232799341;715.4955756191882;734.5959722152814;786.5359437931619;814.0069115678872;14662.486812961548;15765.842341576581;17114.486565403044;17840.553986150353;17849.375271865487;16900.661986760257;17044.914775426107;17076;17101;17170.31318747252;620.3765215957956;871.8038441350677;884.2704768565959;887.8801361816169;888.1118881118881;703.76775664031;824.5918270456133;852.0925214646413;889.3907673243849;893.6648756716231;930.1675000874213;930.316217580078;931.5621657820814;932.3287233192091;932.8880534335889;915.5422288855572;924.4869097650801;924.5885580916495;926.2451102351596;926.4395041000199;677.5392732941598;685.25649670108;685.2633419074465;685.6571714142929;686.3994005245411;15282.617934551614;15649.29578168985;15698.136602486858;15795.842041579552;16085.356585736556;14163.716725665532;14318.785218221736;14403.639909002359;14407;14413.207273599948;11776.646700598996;11819.881801181964;11916.34460104694;12025.218360806608;12360.690982725413;3692.298463291966;3695.796731179784;3722.1253005543676;3723.255348930225;3856.5179352580926;107983;109348;109558;110402;112223.43888280506;8777.868331975025;11133.888661113448;11173.944130279297;11424.485898134604;11861.449788295558;22355.66466503004;22627.321180364615;22658.66012009821;22692.432689182904;22696.205632802772;6765.221999470025;7861.685532578633;7912.881306780403;7971.8405631887035;8094.959525202336;4243.851465198703;4312.137572485515;4362.454693163355;4398.8020539075815;4481.484629267611;3206.8075915445147;3316.2372654289647;3329.8501567429525;3335.149536868083;3364.327134573095;545.4164044472456;554.3320299039648;658.1937127019363;674.416629615389;677.0791723256367;636.2587585462976;654.9443297319685;681.608075356662;685.67773146621;714.8498815248752;10029.849552256723;10346.896531034743;10369.948150259352;10389.53247103867;10507.94746026265;515.9268721060185;555.2337773872031;583.3845293215699;619.4239357397663;659.577870163096;3551.5027896094566;4412.889677758018;4520.073384956077;4979.3526841510375;4983.6013118950395;101590.98409015815;104885;105038.47480762548;105965;106468.93531064744;60015.39984600098;70352.64823675952;70622;72337.27662723305;78800.60599696965;63124.68437657874;65306.67346663236;65958.68082638417;66387.6722465548;70420.64789676122;10476.52855621514;10825.512851921525;11060;11104.944475277573;11115.499802508744;45604.543954561355;49007.75496122639;49968.50094497171;49999.75000124976;50776.23835642542;67053;67165.97753101024;68559;68591.1120221161;68606.65696671484;12849.486020559072;13025.153365031243;13049.869501305246;13175.209487430784;13211.735765284448;14459.566213013626;14481;14516.34676439584;14761.852381476048;14963.77554336664;13457.327133643083;13605.251711155981;13714.971377146954;13743.175409475463;13753.587392378246;13468.79796803029;13661.726765464833;13689.931550342184;13924.51264205748;14047.08693934891;10449;11890.821637675268;11979.520819167135;13568.457261709422;13717.862821371658;10646.520906559068;10911.122640379946;10930.617428389969;11424;11698.883011169779;10479.79040419172;10975.767785408927;11240.606578769704;11281.379524125923;11289.153313501685 08:05:35 INFO - PROCESS | 1644 | __end_tp_report 08:05:35 INFO - PROCESS | 1644 | __start_cc_report 08:05:35 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,376 08:05:35 INFO - PROCESS | 1644 | __end_cc_report 08:05:35 INFO - PROCESS | 1644 | __startTimestamp1483891535017__endTimestamp 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | ------- Summary: start ------- 08:05:35 INFO - PROCESS | 1644 | Number of tests: 6 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5602.19 Median:5797.91 stddev:638.66 (11.0%) stddev-sans-first:615.35 08:05:35 INFO - PROCESS | 1644 | Values: 3334.6 3358.5 3363.5 3386.2 3387.5 5474.6 5485.5 5503.9 5512.6 5525.6 5442.2 5494.5 5536.3 5572.7 5581.9 5077.2 5118.0 5142.2 5165.2 5180.2 5056.6 5112.0 5157.6 5170.5 5176.3 4127.9 4454.8 5155.0 5185.9 5203.1 5041.1 5078.7 5167.9 5169.5 5217.0 4995.4 5025.5 5038.7 5102.8 5137.3 5276.0 5457.6 5471.9 5515.0 5532.7 6083.3 6128.2 6133.3 6155.8 6157.2 6055.2 6098.8 6116.0 6116.2 6144.9 5713.6 5766.6 5773.6 5789.7 5812.4 6264.8 6267.3 6270.5 6280.2 6284.0 5889.8 5943.3 5945.2 6106.3 6137.9 4603.4 5415.9 5481.8 6121.8 6144.8 4590.7 4761.0 6001.0 6020.3 6173.9 5555.4 5629.8 6035.0 6088.2 6167.1 4960.4 4986.3 4987.5 4988.3 4990.5 4369.1 4375.4 4386.6 4387.9 4446.9 5758.3 5772.9 5821.0 5854.1 5879.9 5797.9 6148.4 6155.2 6181.9 6184.1 5662.9 5705.2 5707.3 5786.1 5836.1 5291.9 5359.5 5359.7 5366.5 5419.8 6273.5 6277.2 6281.3 6288.4 6304.7 6031.0 6049.3 6151.7 6198.1 6200.8 6147.3 6147.6 6232.1 6251.2 6378.7 6093.7 6101.9 6117.0 6117.5 6133.4 6036.7 6126.8 6133.4 6164.8 6169.3 5832.2 5888.0 5891.4 5928.3 5964.3 5938.8 5942.2 5945.5 5947.4 5966.6 5083.3 5517.8 5758.5 5785.6 5828.0 5935.9 6015.7 6018.2 6020.0 6026.5 5520.7 5823.6 5826.6 5851.2 5877.2 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:67934.86 Median:39017.51 stddev:145497.20 (372.9%) stddev-sans-first:145817.08 08:05:35 INFO - PROCESS | 1644 | Values: 429.9 462.5 478.8 509.1 521.5 32900.7 39112.0 41110.8 41214.2 43338.0 42164.8 42399.0 42482.0 42544.4 42654.8 8468.0 9404.0 9562.0 9564.9 9574.1 8555.8 11201.3 11489.9 11502.5 11761.8 11527.0 11573.0 11575.9 11599.4 11629.7 11456.5 11473.5 11495.7 11507.5 11521.0 10821.8 10999.3 11365.4 11425.3 11439.0 39928.6 39945.6 40097.8 40155.0 40291.0 3996.8 4028.2 4028.5 4035.9 4038.0 29750.9 30227.1 30573.0 30748.7 30850.8 2765.7 2766.3 3251.9 3267.4 3350.2 440237.0 444243.0 446865.0 448013.8 448589.0 58428.0 58615.0 58926.4 59046.0 59071.4 61882.7 62019.7 62026.4 62028.0 62168.8 12316.8 14316.0 15870.5 16078.2 16080.8 11207.9 11499.2 12359.4 12511.6 12577.9 18137.2 18521.2 21842.0 21850.2 22619.9 8534.8 8840.0 8840.4 8864.6 8865.7 810.9 863.3 867.6 879.3 908.3 27113.6 28663.7 29600.9 29745.0 30441.7 983.0 989.1 1014.5 1019.0 1208.4 11918.2 12138.2 15344.8 15556.4 15808.4 629137.0 629138.0 633749.0 635969.0 636792.0 57885.0 58254.0 58651.7 58701.0 58816.4 1254.4 1417.3 1456.3 1477.1 1479.9 28325.7 28373.4 28820.3 29599.9 29711.0 40566.0 47563.0 48943.0 49228.6 49444.3 538805.0 540670.0 541802.0 542532.0 543056.0 34766.3 35758.3 40903.6 41461.0 42258.8 41450.0 43968.0 46081.5 46107.5 46793.8 38421.6 38509.0 39107.2 39272.2 39683.6 32426.2 37905.8 38163.6 38927.8 39375.0 37067.3 37641.0 37758.8 37759.2 37834.0 29598.6 31596.4 33887.8 33936.2 34150.8 21214.7 21705.7 22709.7 22833.0 22940.2 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:39011.13 Median:2658.86 stddev:117105.84 (4404.4%) stddev-sans-first:117398.39 08:05:35 INFO - PROCESS | 1644 | Values: 361.1 387.6 387.8 396.4 421.6 13978.9 16112.6 20273.4 20940.0 21010.9 20401.7 20870.9 20992.8 21672.6 21790.1 5877.4 5935.5 6007.8 6083.7 6091.0 5009.7 5982.6 6099.4 6106.3 6110.0 5924.9 6065.2 6066.6 6070.5 6075.9 5492.5 6059.8 6061.9 6064.4 6382.4 6089.5 6099.4 6113.7 6124.8 6126.6 13497.9 25152.0 25292.4 25595.0 26344.5 2559.1 2600.7 2653.8 2663.9 2972.2 9309.3 9872.1 14164.8 15109.0 15960.4 1558.8 1746.0 1843.9 1851.5 1884.4 119554.0 129087.0 130029.3 132468.0 133047.0 11499.4 12024.5 12069.9 12082.5 12192.1 15517.9 15558.5 15562.6 15567.8 15583.7 7199.6 8718.7 8833.2 8907.3 8984.4 6850.6 8602.7 8650.3 8750.0 8760.2 4741.5 4822.4 4823.8 4840.1 4925.8 2026.3 2068.4 2073.0 2074.3 2079.1 764.2 862.9 872.4 898.9 916.8 20991.6 21071.8 21975.9 21991.2 22022.6 700.0 828.6 840.7 904.9 916.9 9296.5 9859.7 11625.3 11666.0 11741.4 622885.7 700327.0 709827.5 740916.0 746135.0 107580.0 116707.0 116803.0 123202.0 139237.0 980.7 1061.5 1072.9 1073.8 1075.5 20407.5 20615.0 20687.5 20713.0 21025.8 61232.1 63235.7 63765.7 64461.4 65431.3 90437.0 92338.0 92886.0 94459.0 94571.0 8464.4 8495.4 8544.7 8844.0 8935.7 10956.3 12861.7 13699.9 13869.0 13998.9 11687.8 11708.5 11791.5 12030.6 12141.6 11637.1 11678.4 11697.9 11714.5 11742.9 9460.0 11726.0 11908.8 12057.6 12156.9 8284.9 8522.6 8673.8 8812.8 8930.2 8746.8 11468.6 11672.6 11822.6 12031.8 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5329.67 Median:5531.96 stddev:2889.12 (52.2%) stddev-sans-first:2882.49 08:05:35 INFO - PROCESS | 1644 | Values: 1447.6 1465.8 1466.8 1474.1 1493.4 7505.3 7754.3 8008.7 8412.4 8449.0 6646.0 7887.0 8189.8 8303.0 8310.5 2316.0 2351.9 2359.1 2359.4 2367.7 2341.3 2369.3 2370.1 2370.1 2380.4 2308.1 2325.9 2336.8 2340.0 2340.8 2299.0 2308.6 2330.6 2330.8 2335.6 2269.7 2302.0 2307.7 2311.5 2331.6 7187.4 8148.7 8319.3 8378.1 8389.7 5032.0 5198.3 5199.5 5200.5 5208.4 3356.6 3378.3 3507.7 3573.3 3592.9 1159.8 1183.0 1225.7 1227.9 1246.5 10589.5 10700.4 10742.3 10768.0 10778.6 4354.1 4633.7 4646.8 4693.1 4709.1 8305.5 8341.5 9338.3 9387.0 9447.4 5174.5 5175.1 5193.2 5193.7 5200.0 3533.8 3542.6 3847.9 4239.6 4747.0 3199.9 3767.9 3842.5 3872.6 3915.4 1275.1 1275.9 1279.5 1290.3 1294.2 868.2 895.9 919.5 923.9 931.6 5578.7 5594.4 5741.9 5761.8 5900.0 571.7 582.4 593.2 594.1 596.1 2789.0 3667.2 3691.7 3791.9 3841.7 9675.8 9845.5 9932.5 9954.2 9954.4 9844.5 9918.8 9919.3 9921.5 9979.8 5733.8 7357.1 7756.5 7858.2 7887.0 5485.2 5588.3 5603.3 5609.5 5616.6 8686.0 8709.7 8813.3 8834.1 8858.4 8809.8 8860.3 8903.6 8917.6 9061.3 6520.3 6701.1 6783.3 6982.0 7026.2 7652.7 7779.8 7814.8 7815.6 7864.0 6979.5 7111.8 7144.6 7150.5 7237.6 6882.5 6918.9 6952.4 7134.7 7262.3 6299.6 6402.4 6520.0 6522.3 6523.7 4819.0 4872.9 5027.8 5030.0 5155.2 4946.7 5080.6 5120.3 5169.9 5216.9 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4485.81 Median:5021.82 stddev:1383.42 (27.5%) stddev-sans-first:1351.08 08:05:35 INFO - PROCESS | 1644 | Values: 295.7 330.4 365.2 369.1 372.2 4880.0 5174.4 5374.1 5421.3 5489.9 4657.5 5303.6 5329.1 5347.8 5393.8 3632.5 3797.9 3992.8 4094.5 4122.9 3487.7 3956.0 4165.6 4174.7 4181.3 4159.9 4176.3 4176.3 4185.5 4197.7 3404.4 3697.7 3698.5 4040.3 4141.7 2835.8 3465.3 3500.2 3516.3 3524.1 4304.0 5177.5 5259.6 5292.5 5298.4 3907.6 3935.6 4627.7 4652.1 4687.0 4558.0 4820.9 5488.3 5552.0 5702.9 3836.1 3841.3 3850.1 3870.0 3882.5 5412.3 5561.8 5644.7 5749.3 5916.4 3569.9 3686.3 3883.5 3899.4 3916.7 3905.9 3934.9 3950.2 3954.3 3969.4 4500.3 4541.8 4605.7 4638.8 4652.2 5696.0 5711.1 5723.3 5772.5 5861.8 1143.4 1164.5 1267.5 1268.9 1292.0 739.5 742.8 756.9 771.0 786.6 4365.6 5051.4 5085.6 5101.0 5119.4 4566.8 5350.8 5395.3 5588.8 5713.3 4459.0 4492.8 4521.4 4526.4 4704.0 2849.5 2885.5 2903.2 2925.4 2933.9 4999.9 5043.8 5276.5 5834.0 5889.3 5152.8 5206.9 5258.6 5739.3 5769.3 5147.7 5731.9 5786.7 5914.4 6041.9 4720.8 4731.8 4798.6 4839.8 5125.5 4714.9 4949.1 5584.5 5793.4 5803.6 5621.4 5654.1 5672.9 5821.6 6032.1 3658.6 3726.7 3896.6 3908.4 3938.2 5867.6 5883.7 5895.4 5898.4 5950.4 4997.5 5093.8 5152.4 5184.8 5705.5 5254.4 5600.8 5606.9 5609.1 5644.4 5322.9 5390.6 5490.9 5645.6 5650.4 5453.5 5630.4 5643.9 5673.9 5675.0 4720.6 5140.8 5550.5 5607.8 5614.6 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19970.57 Median:3323.04 stddev:28186.86 (848.2%) stddev-sans-first:28228.76 08:05:35 INFO - PROCESS | 1644 | Values: 693.8 715.5 734.6 786.5 814.0 14662.5 15765.8 17114.5 17840.6 17849.4 16900.7 17044.9 17076.0 17101.0 17170.3 620.4 871.8 884.3 887.9 888.1 703.8 824.6 852.1 889.4 893.7 930.2 930.3 931.6 932.3 932.9 915.5 924.5 924.6 926.2 926.4 677.5 685.3 685.3 685.7 686.4 15282.6 15649.3 15698.1 15795.8 16085.4 14163.7 14318.8 14403.6 14407.0 14413.2 11776.6 11819.9 11916.3 12025.2 12360.7 3692.3 3695.8 3722.1 3723.3 3856.5 107983.0 109348.0 109558.0 110402.0 112223.4 8777.9 11133.9 11173.9 11424.5 11861.4 22355.7 22627.3 22658.7 22692.4 22696.2 6765.2 7861.7 7912.9 7971.8 8095.0 4243.9 4312.1 4362.5 4398.8 4481.5 3206.8 3316.2 3329.9 3335.1 3364.3 545.4 554.3 658.2 674.4 677.1 636.3 654.9 681.6 685.7 714.8 10029.8 10346.9 10369.9 10389.5 10507.9 515.9 555.2 583.4 619.4 659.6 3551.5 4412.9 4520.1 4979.4 4983.6 101591.0 104885.0 105038.5 105965.0 106468.9 60015.4 70352.6 70622.0 72337.3 78800.6 63124.7 65306.7 65958.7 66387.7 70420.6 10476.5 10825.5 11060.0 11104.9 11115.5 45604.5 49007.8 49968.5 49999.8 50776.2 67053.0 67166.0 68559.0 68591.1 68606.7 12849.5 13025.2 13049.9 13175.2 13211.7 14459.6 14481.0 14516.3 14761.9 14963.8 13457.3 13605.3 13715.0 13743.2 13753.6 13468.8 13661.7 13689.9 13924.5 14047.1 10449.0 11890.8 11979.5 13568.5 13717.9 10646.5 10911.1 10930.6 11424.0 11698.9 10479.8 10975.8 11240.6 11281.4 11289.2 08:05:35 INFO - PROCESS | 1644 | -------- Summary: end -------- 08:05:35 INFO - PROCESS | 1644 | 08:05:35 INFO - TEST-INFO | 1644: exit 0 08:05:35 INFO - TEST-OK | dromaeo_css | took 1117001ms 08:05:35 INFO - TEST-START | kraken 08:05:35 INFO - operating with platform_type : mac_ 08:05:35 INFO - Initialising browser for kraken test... 08:05:35 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpTmrrvP/profile http://localhost:49246/getInfo.html) 08:05:36 INFO - PROCESS | 1651 | 1483891536150 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 08:05:38 INFO - PROCESS | 1651 | 2017-01-08 08:05:38.443 plugin-container[1652:10746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:05:38 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:38 INFO - PROCESS | 1651 | 2017-01-08 08:05:38.446 plugin-container[1652:10746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:05:38 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:38 INFO - PROCESS | 1651 | 2017-01-08 08:05:38.446 plugin-container[1652:10746] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:05:38 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:05:38 INFO - PROCESS | 1651 | colorDepth:24 08:05:38 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:05:38 INFO - PROCESS | 1651 | __metrics 08:05:38 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 08:05:39 INFO - TEST-INFO | 1651: exit 0 08:05:39 INFO - Browser initialized. 08:05:39 INFO - Running cycle 1/1 for kraken test... 08:05:39 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpTmrrvP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:05:41 INFO - PROCESS | 1653 | 2017-01-08 08:05:41.523 plugin-container[1654:10861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:05:41 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:41 INFO - PROCESS | 1653 | 2017-01-08 08:05:41.523 plugin-container[1654:10861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 08:05:41 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:41 INFO - PROCESS | 1653 | 2017-01-08 08:05:41.523 plugin-container[1654:10861] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:05:51 INFO - PROCESS | 1653 | RSS: Main: 232767488 08:05:51 INFO - PROCESS | 1653 | 08:05:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 08:05:55 INFO - PROCESS | 1653 | RSS: Main: 245866496 08:05:55 INFO - PROCESS | 1653 | 08:05:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 08:05:58 INFO - PROCESS | 1653 | RSS: Main: 253513728 08:05:58 INFO - PROCESS | 1653 | 08:06:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 08:06:03 INFO - PROCESS | 1653 | RSS: Main: 256086016 08:06:03 INFO - PROCESS | 1653 | 08:06:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 08:06:05 INFO - PROCESS | 1653 | RSS: Main: 258244608 08:06:05 INFO - PROCESS | 1653 | 08:06:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:06:08 INFO - PROCESS | 1653 | RSS: Main: 258572288 08:06:08 INFO - PROCESS | 1653 | 08:06:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 08:06:11 INFO - PROCESS | 1653 | RSS: Main: 264564736 08:06:11 INFO - PROCESS | 1653 | 08:06:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 08:06:15 INFO - PROCESS | 1653 | RSS: Main: 263749632 08:06:15 INFO - PROCESS | 1653 | 08:06:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 08:06:19 INFO - PROCESS | 1653 | RSS: Main: 263299072 08:06:19 INFO - PROCESS | 1653 | 08:06:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:06:21 INFO - PROCESS | 1653 | RSS: Main: 262742016 08:06:21 INFO - PROCESS | 1653 | 08:06:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:06:24 INFO - PROCESS | 1653 | RSS: Main: 262692864 08:06:24 INFO - PROCESS | 1653 | 08:06:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:06:26 INFO - PROCESS | 1653 | RSS: Main: 263036928 08:06:26 INFO - PROCESS | 1653 | 08:06:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:06:30 INFO - PROCESS | 1653 | RSS: Main: 263385088 08:06:30 INFO - PROCESS | 1653 | 08:06:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:06:34 INFO - PROCESS | 1653 | RSS: Main: 263233536 08:06:34 INFO - PROCESS | 1653 | 08:06:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 08:06:36 INFO - PROCESS | 1653 | RSS: Main: 265146368 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | __start_tp_report 08:06:36 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 08:06:36 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 08:06:36 INFO - PROCESS | 1653 | |i|pagename|runs| 08:06:36 INFO - PROCESS | 1653 | |0;ai-astar;106;119;158;175;104;176;176;174;119;118 08:06:36 INFO - PROCESS | 1653 | |1;audio-beat-detection;178;97;140;183;148;94;102;132;163;146 08:06:36 INFO - PROCESS | 1653 | |2;audio-dft;210;256;273;260;200;199;196;197;197;257 08:06:36 INFO - PROCESS | 1653 | |3;audio-fft;86;65;76;111;86;70;60;62;71;59 08:06:36 INFO - PROCESS | 1653 | |4;audio-oscillator;85;80;118;103;104;104;92;59;64;111 08:06:36 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;94;121;103;92;90;88;93;87;90;87 08:06:36 INFO - PROCESS | 1653 | |6;imaging-darkroom;102;102;115;174;161;100;103;101;100;101 08:06:36 INFO - PROCESS | 1653 | |7;imaging-desaturate;94;88;148;150;93;91;100;134;93;93 08:06:36 INFO - PROCESS | 1653 | |8;json-parse-financial;60;60;59;60;62;61;62;62;62;61 08:06:36 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;77;73;74;87;79;74;72;74;73;73 08:06:36 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;88;90;91;85;89;89;90;99;90;89 08:06:36 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;142;144;174;134;160;156;159;146;190;147 08:06:36 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;176;170;175;180;176;174;174;177;172;181 08:06:36 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;71;69;68;67;68;67;73;81;67;58 08:06:36 INFO - PROCESS | 1653 | __end_tp_report 08:06:36 INFO - PROCESS | 1653 | __start_cc_report 08:06:36 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,927 08:06:36 INFO - PROCESS | 1653 | __end_cc_report 08:06:36 INFO - PROCESS | 1653 | __startTimestamp1483891596099__endTimestamp 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | ------- Summary: start ------- 08:06:36 INFO - PROCESS | 1653 | Number of tests: 14 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:142.50 Median:166.00 stddev:31.71 (19.1%) stddev-sans-first:30.77 08:06:36 INFO - PROCESS | 1653 | Values: 106.0 119.0 158.0 175.0 104.0 176.0 176.0 174.0 119.0 118.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:170.50 stddev:32.26 (18.9%) stddev-sans-first:30.85 08:06:36 INFO - PROCESS | 1653 | Values: 178.0 97.0 140.0 183.0 148.0 94.0 102.0 132.0 163.0 146.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:224.50 Median:233.00 stddev:32.40 (13.9%) stddev-sans-first:33.94 08:06:36 INFO - PROCESS | 1653 | Values: 210.0 256.0 273.0 260.0 200.0 199.0 196.0 197.0 197.0 257.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:74.60 Median:70.50 stddev:16.08 (22.8%) stddev-sans-first:16.52 08:06:36 INFO - PROCESS | 1653 | Values: 86.0 65.0 76.0 111.0 86.0 70.0 60.0 62.0 71.0 59.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:61.50 stddev:19.75 (32.1%) stddev-sans-first:20.79 08:06:36 INFO - PROCESS | 1653 | Values: 85.0 80.0 118.0 103.0 104.0 104.0 92.0 59.0 64.0 111.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:90.00 stddev:10.43 (11.6%) stddev-sans-first:11.06 08:06:36 INFO - PROCESS | 1653 | Values: 94.0 121.0 103.0 92.0 90.0 88.0 93.0 87.0 90.0 87.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:115.90 Median:102.50 stddev:27.71 (27.0%) stddev-sans-first:28.94 08:06:36 INFO - PROCESS | 1653 | Values: 102.0 102.0 115.0 174.0 161.0 100.0 103.0 101.0 100.0 101.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:108.40 Median:92.00 stddev:25.08 (27.3%) stddev-sans-first:26.06 08:06:36 INFO - PROCESS | 1653 | Values: 94.0 88.0 148.0 150.0 93.0 91.0 100.0 134.0 93.0 93.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:61.50 stddev:1.10 (1.8%) stddev-sans-first:1.12 08:06:36 INFO - PROCESS | 1653 | Values: 60.0 60.0 59.0 60.0 62.0 61.0 62.0 62.0 62.0 61.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:75.60 Median:74.00 stddev:4.53 (6.1%) stddev-sans-first:4.77 08:06:36 INFO - PROCESS | 1653 | Values: 77.0 73.0 74.0 87.0 79.0 74.0 72.0 74.0 73.0 73.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:90.00 stddev:3.56 (4.0%) stddev-sans-first:3.70 08:06:36 INFO - PROCESS | 1653 | Values: 88.0 90.0 91.0 85.0 89.0 89.0 90.0 99.0 90.0 89.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:155.20 Median:157.50 stddev:16.68 (10.6%) stddev-sans-first:16.99 08:06:36 INFO - PROCESS | 1653 | Values: 142.0 144.0 174.0 134.0 160.0 156.0 159.0 146.0 190.0 147.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:176.00 stddev:3.34 (1.9%) stddev-sans-first:3.54 08:06:36 INFO - PROCESS | 1653 | Values: 176.0 170.0 175.0 180.0 176.0 174.0 174.0 177.0 172.0 181.0 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.50 stddev:5.76 (8.4%) stddev-sans-first:6.06 08:06:36 INFO - PROCESS | 1653 | Values: 71.0 69.0 68.0 67.0 68.0 67.0 73.0 81.0 67.0 58.0 08:06:36 INFO - PROCESS | 1653 | -------- Summary: end -------- 08:06:36 INFO - PROCESS | 1653 | 08:06:36 INFO - TEST-INFO | 1653: exit 0 08:06:36 INFO - TEST-OK | kraken | took 61295ms 08:06:36 INFO - SUITE-END | took 1178s 08:06:37 INFO - Completed test suite (00:19:38) 08:06:37 INFO - javascript benchmark 08:06:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7260.878104280405, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.5665063541737, 3358.4626459766437, 3363.4786608075756, 3386.238096428304, 3387.4749413840855, 5474.562035037197, 5485.533729632981, 5503.862403439916, 5512.614117011811, 5525.613207075507, 5442.156465747806, 5494.532964697995, 5536.335639723229, 5572.749226284816, 5581.888362232763, 5077.162268225738, 5118.0, 5142.151544995071, 5165.225216217578, 5180.2488639147305, 5056.595472362221, 5112.0, 5157.587393008569, 5170.53465188133, 5176.275321454999, 4127.917441651165, 4454.774936892348, 5155.0, 5185.922211166836, 5203.063448579254, 5041.117804384255, 5078.669886457368, 5167.948320516822, 5169.53474187325, 5217.0, 4995.375578052744, 5025.522575355335, 5038.748062596856, 5102.770375333082, 5137.306463627438, 5275.971185618806, 5457.5633949283965, 5471.863203419906, 5514.972425137848, 5532.695701736403, 6083.30042045166, 6128.172696685935, 6133.325334213233, 6155.784547540859, 6157.230346206724, 6055.152278680988, 6098.817035488943, 6116.0, 6116.153172518083, 6144.893919094561, 5713.571482138815, 5766.625169363978, 5773.624714393551, 5789.710514474302, 5812.447817457335, 6264.843378915563, 6267.2792628848265, 6270.467010304084, 6280.152179455807, 6284.0, 5889.793857214977, 5943.257092863392, 5945.1973983512535, 6106.297775755845, 6137.938620613826, 4603.355530225704, 5415.889742602757, 5481.835544933659, 6121.757838957688, 6144.754209831646, 4590.724556526619, 4760.952390476051, 6001.0, 6020.307664618625, 6173.9073913891325, 5555.416681248411, 5629.802956896489, 6034.969825150846, 6088.178095957078, 6167.105769663373, 4960.355153829979, 4986.252062190628, 4987.451380348159, 4988.301637770715, 4990.451050384455, 4369.148016136823, 4375.365571992043, 4386.561343865588, 4387.934180987288, 4446.888827779331, 5758.280214973129, 5772.855678607984, 5820.970895145582, 5854.092615644582, 5879.853003674943, 5797.913031304533, 6148.354422785633, 6155.230596175478, 6181.876362472725, 6184.072389141575, 5662.915056274159, 5705.22979397776, 5707.258056452634, 5786.103154011135, 5836.095405212114, 5291.867703307371, 5359.544438722674, 5359.651622644516, 5366.543843773245, 5419.783208671609, 6273.52948528853, 6277.246730392382, 6281.3404592518045, 6288.434040936338, 6304.71628776697, 6031.0, 6049.274087109575, 6151.692415379123, 6198.101275315144, 6200.844978875564, 6147.262328520606, 6147.631142131487, 6232.09634602971, 6251.249850018027, 6378.74485020594, 6093.695315234132, 6101.871153836554, 6116.969415152896, 6117.510599151969, 6133.3866613338305, 6036.6679832609625, 6126.754929802758, 6133.447989680772, 6164.815055548341, 6169.259688837368, 5832.212651292021, 5888.028475301528, 5891.381404952418, 5928.288605367384, 5964.31410387811, 5938.762449501972, 5942.227510423617, 5945.464908158287, 5947.405259474191, 5966.582339236385, 5083.2883396323805, 5517.779288828402, 5758.510526605285, 5785.595008349375, 5828.0, 5935.851603709942, 6015.6691381974415, 6018.157457955977, 6020.0, 6026.548008899263, 5520.70263488088, 5823.621464604787, 5826.621269617462, 5851.180834683233, 5877.235959325431], "value": 5563.764732885727, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.88178250980997, 462.48432997208124, 478.8443755779373, 509.0938130128372, 521.4889408379788, 32900.67099329006, 39112.0, 41110.7666769997, 41214.17571648565, 43338.0, 42164.78917605407, 42399.0, 42482.0, 42544.361834572504, 42654.786726066326, 8468.0, 9403.95298023509, 9562.0, 9564.90435095647, 9574.13832755052, 8555.828883422328, 11201.271917325388, 11489.885101148966, 11502.539898404053, 11761.823572646417, 11527.0, 11573.016293615054, 11575.884241157606, 11599.362035088066, 11629.651110466657, 11456.48445819936, 11473.541058357656, 11495.712607184803, 11507.482163302671, 11521.0, 10821.83767243492, 10999.285046472034, 11365.374904380256, 11425.257358271769, 11439.027682646985, 39928.600713993066, 39945.60054399477, 40097.79951100255, 40155.0, 40290.99272518211, 3996.840126394941, 4028.194361127757, 4028.47629808126, 4035.858744943913, 4037.9596204037875, 29750.851245743634, 30227.0931872042, 30573.0, 30748.69251307503, 30850.845745771352, 2765.7234276572385, 2766.2669392610974, 3251.9349613007844, 3267.3628642414737, 3350.1959529712694, 440237.0, 444243.0, 446865.0, 448013.7599312015, 448589.0, 58428.0, 58615.0, 58926.410735892096, 59046.0, 59071.40928590745, 61882.69058654768, 62019.689901550206, 62026.379736202965, 62028.0, 62168.756624868154, 12316.753664926651, 14315.997880148498, 15870.52388428349, 16078.196090195443, 16080.758788618181, 11207.887921120684, 11499.195056345976, 12359.38203089842, 12511.624651260478, 12577.87422125767, 18137.189064518792, 18521.166547505396, 21842.0, 21850.23524176678, 22619.88690056539, 8534.829303413897, 8840.010997525505, 8840.425372350777, 8864.601092950832, 8865.650602590318, 810.8621534339059, 863.2748491267365, 867.5878957495265, 879.2658130461009, 908.3051465628799, 27113.593296100575, 28663.71336286652, 29600.851995740315, 29745.0, 30441.69558304433, 982.9950850245846, 989.080155455419, 1014.452195814252, 1018.9643362482277, 1208.4380762945273, 11918.225315354473, 12138.237731972018, 15344.769828452581, 15556.377744890304, 15808.367665293259, 629137.0, 629138.0, 633749.0, 635969.0, 636792.0, 57885.0, 58254.0, 58651.70674146687, 58701.0, 58816.41183588195, 1254.4292346982102, 1417.2630232279364, 1456.3155317000992, 1477.09158867298, 1479.9112053276838, 28325.71674283231, 28373.432531348844, 28820.279493012844, 29599.85200073986, 29711.0, 40566.0, 47563.0, 48943.02113957772, 49228.60028196455, 49444.258336124265, 538805.0, 540670.0, 541802.0, 542532.0, 543056.0, 34766.30467390689, 35758.28483430265, 40903.59096408998, 41460.96347591334, 42258.78870605627, 41450.0, 43968.0, 46081.539184607725, 46107.53892461032, 46793.76603116962, 38421.61578384292, 38509.0, 39107.2178556433, 39272.214555709295, 39683.60316396799, 32426.189345266557, 37905.81047094857, 38163.61836381601, 38927.80536097301, 39375.0, 37067.25865482729, 37641.0, 37758.81120594379, 37759.244815104095, 37834.0, 29598.556021660122, 31596.368072638877, 33887.83056084704, 33936.15159621029, 34150.80472183462, 21214.681779773626, 21705.674414883473, 22709.659355109357, 22833.0, 22940.197093101662], "value": 20202.611247887417, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.14949294411656, 387.5852837463911, 387.7781675045794, 396.41924580489587, 421.593162598093, 13978.930105349458, 16112.59718507035, 20273.39179824606, 20940.0, 21010.949452527388, 20401.693974590355, 20870.89564552179, 20992.790072099273, 21672.566548669056, 21790.128394864227, 5877.441643043925, 5935.545791281132, 6007.7596896124105, 6083.665398403086, 6091.0, 5009.749512524378, 5982.61113027654, 6099.390060993887, 6106.328303886569, 6109.969450152743, 5924.911126333109, 6065.211522502091, 6066.636001839904, 6070.453659170674, 6075.939240607581, 5492.505674489315, 6059.818205453844, 6061.878762424749, 6064.363241859607, 6382.393672601095, 6089.512838972916, 6099.420555047263, 6113.694315284218, 6124.785632502841, 6126.632402055846, 13497.865021349759, 25152.0, 25292.367690807692, 25595.0, 26344.47311053768, 2559.091522509501, 2600.7139214686367, 2653.8009649276382, 2663.9200823975116, 2972.1677930179367, 9309.30180236485, 9872.111509964137, 14164.787528187086, 15109.0, 15960.441384551601, 1558.7895634089366, 1745.95635109122, 1843.8524918006526, 1851.472330385847, 1884.3969929622526, 119554.0, 129087.0, 130029.34985325013, 132468.0, 133047.0, 11499.3675347855, 12024.458899349374, 12069.879301207051, 12082.516699332104, 12192.146549741434, 15517.922410388102, 15558.533244002698, 15562.610934726723, 15567.766483502757, 15583.688326233412, 7199.568025918462, 8718.65125394977, 8833.205011548991, 8907.331950103768, 8984.416012959136, 6850.623215723183, 8602.698905538276, 8650.307975362082, 8750.0, 8760.211580957744, 4741.502142275081, 4822.421309442819, 4823.782929768169, 4840.104580652591, 4925.827596034193, 2026.3009261804655, 2068.43118142509, 2072.9792702073087, 2074.2740040985536, 2079.074811708775, 764.170874601053, 862.9180227878342, 872.438335379676, 898.910108989109, 916.8258030974093, 20991.580168396547, 21071.789282106984, 21975.901204939688, 21991.230306939182, 22022.559548809255, 699.964053201265, 828.6271177969937, 840.7430890818143, 904.8914130304274, 916.8533034714413, 9296.535173241446, 9859.654912078044, 11625.314329422186, 11666.0, 11741.354225517507, 622885.7114288535, 700327.0, 709827.4508627424, 740916.0, 746135.0, 107580.0, 116707.0, 116803.0, 123202.0, 139237.0, 980.6959842448864, 1061.4798748613123, 1072.940988245654, 1073.8228192348174, 1075.473018221066, 20407.4898127548, 20615.0, 20687.482812929797, 20712.96435178265, 21025.789742102384, 61232.08151877815, 63235.6838215806, 63765.681171593846, 64461.35538644553, 65431.34568654443, 90437.0, 92338.0, 92886.0, 94459.0, 94571.0, 8464.407491475538, 8495.447795893248, 8544.700935467228, 8844.0, 8935.687250946447, 10956.34261944286, 12861.67845803825, 13699.863001369858, 13869.0, 13998.930005349908, 11687.824682629598, 11708.531658733555, 11791.528338866348, 12030.578929737418, 12141.57504487374, 11637.069034477052, 11678.416079196177, 11697.941510292394, 11714.531418743496, 11742.88257117418, 9460.0, 11726.0, 11908.821367679318, 12057.57798477049, 12156.878431215575, 8284.917150828414, 8522.573871306535, 8673.826523469621, 8812.823743524965, 8930.151635594608, 8746.781330466789, 11468.598599048993, 11672.649820505398, 11822.586209482626, 12031.819522707341], "value": 9981.520537710427, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.6380904773807, 1465.8314293856197, 1466.7726502392127, 1474.0787008119926, 1493.4175671488122, 7505.324520793128, 7754.302112809846, 8008.719694810676, 8412.36907231957, 8448.957755211231, 6645.966770166143, 7887.0, 8189.8362032759305, 8302.958485207566, 8310.501369917809, 2315.9652605210936, 2351.905923763047, 2359.103540654549, 2359.4219416243045, 2367.7277113132036, 2341.309313752439, 2369.3247424484025, 2370.0875163062174, 2370.0993622423457, 2380.4167978845207, 2308.076769292288, 2325.94185145371, 2336.7663233676667, 2339.9766002340016, 2340.8361414700894, 2298.9654655404966, 2308.607536718762, 2330.6387509935985, 2330.848494847838, 2335.556244313575, 2269.716285464317, 2301.953960920789, 2307.746147923727, 2311.4914718761847, 2331.63859601762, 7187.425005999507, 8148.67405303793, 8319.251267385904, 8378.078411374743, 8389.748307550783, 5032.018756342514, 5198.298229738974, 5199.506046925536, 5200.5059519345605, 5208.427073021964, 3356.580427446569, 3378.3074469733647, 3507.7018453431488, 3573.3032058748554, 3592.892213233607, 1159.7506536094695, 1182.9940850295693, 1225.7497352700284, 1227.858796238426, 1246.4515613130193, 10589.470526473646, 10700.357978521313, 10742.3017503862, 10768.0, 10778.622748203776, 4354.129174165179, 4633.745144017044, 4646.83736069236, 4693.084848454587, 4709.128811169944, 8305.46014509055, 8341.45780524264, 9338.299627527962, 9386.95306523463, 9447.385919915183, 5174.456682048406, 5175.146100893386, 5193.221016847428, 5193.662411943212, 5200.037992971313, 3533.787972721645, 3542.557180352456, 3847.903347545936, 4239.554846741109, 4746.976265118652, 3199.9200019999685, 3767.9246415071543, 3842.51968503937, 3872.5546562145714, 3915.4126880967515, 1275.0691994843817, 1275.8724127587168, 1279.5305061231911, 1290.316132449803, 1294.2428679222571, 868.1708967935606, 895.892492900856, 919.5402298850574, 923.8995890478731, 931.5854444772147, 5578.721063946787, 5594.412586678422, 5741.942580574141, 5761.798337058183, 5900.0, 571.7227144834752, 582.4466756581264, 593.2465768474013, 594.0851089322396, 596.1088173181122, 2788.9581656275172, 3667.156553992597, 3691.667749902468, 3791.9241615167543, 3841.7310788245113, 9675.758106047406, 9845.458499782437, 9932.453715045598, 9954.203663706887, 9954.402735835729, 9844.458554779412, 9918.801623967625, 9919.256055795844, 9921.50392480373, 9979.800403992025, 5733.799317023968, 7357.117145942521, 7756.53460792343, 7858.17489163641, 7886.960565197022, 5485.204645326485, 5588.329400471969, 5603.271574695264, 5609.523190528687, 5616.634918730269, 8685.956570217108, 8709.695160669346, 8813.25087367581, 8834.116588341114, 8858.424202426822, 8809.823803524021, 8860.335474839283, 8903.599338029675, 8917.554122293988, 9061.275097992013, 6520.282768895512, 6701.128853249046, 6783.253842077466, 6981.965090174516, 7026.227115017242, 7652.693892244248, 7779.844403111792, 7814.843703125792, 7815.648295826588, 7863.977682901186, 6979.511434199558, 7111.82220444493, 7144.607046612487, 7150.499465037397, 7237.601931893796, 6882.483813713891, 6918.930810691829, 6952.443804495527, 7134.714611415485, 7262.273772622695, 6299.559030867978, 6402.35976402356, 6520.0, 6522.315156908457, 6523.706433210612, 4819.036192761532, 4872.853814385574, 5027.773750181177, 5030.0, 5155.175171972466, 4946.678465899705, 5080.593552515864, 5120.3133743704475, 5169.870753231199, 5216.947830521646], "value": 4316.2833330970525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.7190668864578, 330.40856866209486, 365.2202547560958, 369.10492056762354, 372.1644907183372, 4879.950031230481, 5174.352081782344, 5374.050073196203, 5421.295231619889, 5489.862753431166, 4657.4876763555985, 5303.628745987782, 5329.147336426173, 5347.839564813052, 5393.83818485445, 3632.4914511968345, 3797.905052373699, 3992.8003599819967, 4094.5086589609286, 4122.8969275768195, 3487.686108250257, 3956.0109972506875, 4165.563674686449, 4174.70777045607, 4181.310083836163, 4159.854405095823, 4176.2900306947895, 4176.331786914095, 4185.497740271172, 4197.7271477354025, 3404.4042292598942, 3697.7041836653, 3698.500702405181, 4040.252628212814, 4141.730787498803, 2835.8440285784272, 3465.324261768956, 3500.1949551603275, 3516.2967406518796, 3524.0661224775454, 4303.956960430418, 5177.48225177483, 5259.631825772198, 5292.470752924715, 5298.443663415363, 3907.628775266345, 3935.60643935607, 4627.699199552052, 4652.092841895832, 4687.015726697397, 4557.954420455786, 4820.927686084711, 5488.25908502355, 5552.02902969444, 5702.914456283159, 3836.0601652595014, 3841.2509560635685, 3850.0567360996456, 3870.0262865938294, 3882.456456096149, 5412.296401467824, 5561.833145005657, 5644.673501727091, 5749.252597162342, 5916.437938395846, 3569.910752231163, 3686.336459437327, 3883.514560679915, 3899.395593682984, 3916.74541154824, 3905.902352441212, 3934.9016274593364, 3950.150717595731, 3954.268460334847, 3969.4244334571326, 4500.324951257272, 4541.772911354486, 4605.677602567788, 4638.7502061500145, 4652.232381656989, 5696.0, 5711.114777209456, 5723.341815691166, 5772.509336706427, 5861.824145275648, 1143.4397145398696, 1164.5050853387277, 1267.5056727876135, 1268.898488120948, 1291.9935400323127, 739.5320289225882, 742.7957311739207, 756.9053868266467, 770.9768706938801, 786.6460092958077, 4365.581186114526, 5051.419086804991, 5085.64400491962, 5101.0, 5119.360079990001, 4566.771661416916, 5350.803169736698, 5395.331174801095, 5588.846276765747, 5713.314402271754, 4458.974435879766, 4492.842750503717, 4521.434820647419, 4526.366308716782, 4703.95296047042, 2849.4782117825876, 2885.5238885584063, 2903.187107609873, 2925.3564215872475, 2933.853307334625, 4999.850004499871, 5043.798248070036, 5276.498732620395, 5833.970830145822, 5889.322727886262, 5152.793888244503, 5206.854492011751, 5258.605604579672, 5739.282589676291, 5769.269219234213, 5147.6654017488745, 5731.933415622622, 5786.681732504669, 5914.4381283777975, 6041.885340131007, 4720.811167553259, 4731.787069581946, 4798.648662155589, 4839.758012099311, 5125.539740924302, 4714.905701885875, 4949.050949050949, 5584.46947539983, 5793.391693872083, 5803.593748437569, 5621.409751975983, 5654.095344744821, 5672.943270567242, 5821.565276486893, 6032.0791485534755, 3658.560972683295, 3726.6646001859963, 3896.6298201670797, 3908.4332771747368, 3938.2123575284486, 5867.559933005127, 5883.73476211653, 5895.4399332063385, 5898.351181370132, 5950.404959504188, 4997.475265097112, 5093.83512007388, 5152.381714194321, 5184.766685499237, 5705.518099626675, 5254.395744489432, 5600.803971860965, 5606.915896261477, 5609.074502707008, 5644.350899646429, 5322.866928326823, 5390.601182473762, 5490.917636235539, 5645.633033852787, 5650.350209725934, 5453.482503785116, 5630.352509461464, 5643.887122257614, 5673.914891276551, 5674.971625141848, 4720.575148236675, 5140.791913900305, 5550.5282051026115, 5607.775688972559, 5614.635048721983], "value": 4043.034322348795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.7745232799341, 715.4955756191882, 734.5959722152814, 786.5359437931619, 814.0069115678872, 14662.486812961548, 15765.842341576581, 17114.486565403044, 17840.553986150353, 17849.375271865487, 16900.661986760257, 17044.914775426107, 17076.0, 17101.0, 17170.31318747252, 620.3765215957956, 871.8038441350677, 884.2704768565959, 887.8801361816169, 888.1118881118881, 703.76775664031, 824.5918270456133, 852.0925214646413, 889.3907673243849, 893.6648756716231, 930.1675000874213, 930.316217580078, 931.5621657820814, 932.3287233192091, 932.8880534335889, 915.5422288855572, 924.4869097650801, 924.5885580916495, 926.2451102351596, 926.4395041000199, 677.5392732941598, 685.25649670108, 685.2633419074465, 685.6571714142929, 686.3994005245411, 15282.617934551614, 15649.29578168985, 15698.136602486858, 15795.842041579552, 16085.356585736556, 14163.716725665532, 14318.785218221736, 14403.639909002359, 14407.0, 14413.207273599948, 11776.646700598996, 11819.881801181964, 11916.34460104694, 12025.218360806608, 12360.690982725413, 3692.298463291966, 3695.796731179784, 3722.1253005543676, 3723.255348930225, 3856.5179352580926, 107983.0, 109348.0, 109558.0, 110402.0, 112223.43888280506, 8777.868331975025, 11133.888661113448, 11173.944130279297, 11424.485898134604, 11861.449788295558, 22355.66466503004, 22627.321180364615, 22658.66012009821, 22692.432689182904, 22696.205632802772, 6765.221999470025, 7861.685532578633, 7912.881306780403, 7971.8405631887035, 8094.959525202336, 4243.851465198703, 4312.137572485515, 4362.454693163355, 4398.8020539075815, 4481.484629267611, 3206.8075915445147, 3316.2372654289647, 3329.8501567429525, 3335.149536868083, 3364.327134573095, 545.4164044472456, 554.3320299039648, 658.1937127019363, 674.416629615389, 677.0791723256367, 636.2587585462976, 654.9443297319685, 681.608075356662, 685.67773146621, 714.8498815248752, 10029.849552256723, 10346.896531034743, 10369.948150259352, 10389.53247103867, 10507.94746026265, 515.9268721060185, 555.2337773872031, 583.3845293215699, 619.4239357397663, 659.577870163096, 3551.5027896094566, 4412.889677758018, 4520.073384956077, 4979.3526841510375, 4983.6013118950395, 101590.98409015815, 104885.0, 105038.47480762548, 105965.0, 106468.93531064744, 60015.39984600098, 70352.64823675952, 70622.0, 72337.27662723305, 78800.60599696965, 63124.68437657874, 65306.67346663236, 65958.68082638417, 66387.6722465548, 70420.64789676122, 10476.52855621514, 10825.512851921525, 11060.0, 11104.944475277573, 11115.499802508744, 45604.543954561355, 49007.75496122639, 49968.50094497171, 49999.75000124976, 50776.23835642542, 67053.0, 67165.97753101024, 68559.0, 68591.1120221161, 68606.65696671484, 12849.486020559072, 13025.153365031243, 13049.869501305246, 13175.209487430784, 13211.735765284448, 14459.566213013626, 14481.0, 14516.34676439584, 14761.852381476048, 14963.77554336664, 13457.327133643083, 13605.251711155981, 13714.971377146954, 13743.175409475463, 13753.587392378246, 13468.79796803029, 13661.726765464833, 13689.931550342184, 13924.51264205748, 14047.08693934891, 10449.0, 11890.821637675268, 11979.520819167135, 13568.457261709422, 13717.862821371658, 10646.520906559068, 10911.122640379946, 10930.617428389969, 11424.0, 11698.883011169779, 10479.79040419172, 10975.767785408927, 11240.606578769704, 11281.379524125923, 11289.153313501685], "value": 7483.319524522781, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1616.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 158.0, 175.0, 104.0, 176.0, 176.0, 174.0, 119.0, 118.0], "value": 142.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 97.0, 140.0, 183.0, 148.0, 94.0, 102.0, 132.0, 163.0, 146.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 256.0, 273.0, 260.0, 200.0, 199.0, 196.0, 197.0, 197.0, 257.0], "value": 224.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 65.0, 76.0, 111.0, 86.0, 70.0, 60.0, 62.0, 71.0, 59.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 118.0, 103.0, 104.0, 104.0, 92.0, 59.0, 64.0, 111.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 121.0, 103.0, 92.0, 90.0, 88.0, 93.0, 87.0, 90.0, 87.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 115.0, 174.0, 161.0, 100.0, 103.0, 101.0, 100.0, 101.0], "value": 115.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 88.0, 148.0, 150.0, 93.0, 91.0, 100.0, 134.0, 93.0, 93.0], "value": 108.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 62.0, 61.0, 62.0, 62.0, 62.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 74.0, 87.0, 79.0, 74.0, 72.0, 74.0, 73.0, 73.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 91.0, 85.0, 89.0, 89.0, 90.0, 99.0, 90.0, 89.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 144.0, 174.0, 134.0, 160.0, 156.0, 159.0, 146.0, 190.0, 147.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 170.0, 175.0, 180.0, 176.0, 174.0, 174.0, 177.0, 172.0, 181.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 68.0, 67.0, 68.0, 67.0, 73.0, 81.0, 67.0, 58.0], "value": 68.9, "unit": "score"}]}]} 08:06:37 INFO - Return code: 0 08:06:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:06:37 ERROR - # TBPL SUCCESS # 08:06:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:06:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:06:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:06:37 INFO - 2017-01-08 08:06:37.425 screenresolution[1658:11255] starting screenresolution argv=screenresolution get 08:06:37 INFO - 2017-01-08 08:06:37.427 screenresolution[1658:11255] Display 0: 1600x1200x32@60 08:06:37 INFO - 2017-01-08 08:06:37.433 screenresolution[1659:11258] starting screenresolution argv=screenresolution list 08:06:37 INFO - Available Modes on Display 0 08:06:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:06:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:06:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:06:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:06:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:06:37 INFO - Intel Iris: 08:06:37 INFO - Chipset Model: Intel Iris 08:06:37 INFO - Type: GPU 08:06:37 INFO - Bus: Built-In 08:06:37 INFO - VRAM (Dynamic, Max): 1536 MB 08:06:37 INFO - Vendor: Intel (0x8086) 08:06:37 INFO - Device ID: 0x0a2e 08:06:37 INFO - Revision ID: 0x0009 08:06:37 INFO - Displays: 08:06:37 INFO - Display: 08:06:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:06:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:06:37 INFO - Display Serial Number: 1600x1200 60 08:06:37 INFO - Main Display: Yes 08:06:37 INFO - Mirror: Off 08:06:37 INFO - Online: Yes 08:06:37 INFO - Rotation: Supported 08:06:37 INFO - Return code: 0 08:06:37 INFO - Running post-action listener: _resource_record_post_action 08:06:37 INFO - [mozharness: 2017-01-08 16:06:37.668252Z] Finished run-tests step (success) 08:06:37 INFO - Running post-run listener: _resource_record_post_run 08:06:37 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 33714688; Write bytes: 555274240; Read time: 311; Write time: 1748 08:06:37 INFO - TinderboxPrint: CPU usage
54.6% 08:06:37 INFO - TinderboxPrint: I/O read bytes / time
33,714,688 / 311 08:06:37 INFO - TinderboxPrint: I/O write bytes / time
555,274,240 / 1,748 08:06:37 INFO - TinderboxPrint: CPU idle
2,182.4 (45.4%) 08:06:37 INFO - TinderboxPrint: CPU system
970.0 (20.2%) 08:06:37 INFO - TinderboxPrint: CPU user
1,653.3 (34.4%) 08:06:37 INFO - TinderboxPrint: Swap in / out
279,781,376 / 0 08:06:37 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245989888; Write bytes: 240121856; Read time: 17909; Write time: 522 08:06:37 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 29757952; Write bytes: 301639680; Read time: 280; Write time: 1187 08:06:38 INFO - Running post-run listener: _upload_blobber_files 08:06:38 INFO - Blob upload gear active. 08:06:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:06:38 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 08:06:38 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:06:38 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:06:38 INFO - (blobuploader) - INFO - Open directory for files ... 08:06:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:06:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:06:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:39 INFO - (blobuploader) - INFO - Done attempting. 08:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:06:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:40 INFO - (blobuploader) - INFO - Done attempting. 08:06:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:06:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:06:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:41 INFO - (blobuploader) - INFO - Done attempting. 08:06:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:06:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:06:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:43 INFO - (blobuploader) - INFO - Done attempting. 08:06:43 INFO - (blobuploader) - INFO - Iteration through files over. 08:06:43 INFO - Return code: 0 08:06:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:06:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:06:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9384aced6efdc5dfa62dc663c353edc20734fc1b28edea3527ab5664eb8ce5a51be8f8bb2c194226054ef98a671b88e2fa2483e7f36700eb99e87aa6b657c521", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50877712797efec0113b73fba80609fd3f9764bbc190aa31a8d16886420775cf778d4f00083542b0dfe1a2f6332b4feef9db6b1e0d95a6cca6de1a50db4de07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e50e02fb82233b04d0ef50ab6535208403e5033dc62bc7fc3b42c5297501d35c99ea5f8f4b9809f63b2192634a13bee92992787e314e736cce7e922f5a3ab4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d84adf49aea8a2ca3b8315361e966008b28f13e9bd81052637ab190893aef8500dacc4a92a9b0c3004440c4c757de907bd41cbe1cabb96cc0a2a8f350f7e67"} 08:06:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:06:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:06:43 INFO - Contents: 08:06:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9384aced6efdc5dfa62dc663c353edc20734fc1b28edea3527ab5664eb8ce5a51be8f8bb2c194226054ef98a671b88e2fa2483e7f36700eb99e87aa6b657c521", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50877712797efec0113b73fba80609fd3f9764bbc190aa31a8d16886420775cf778d4f00083542b0dfe1a2f6332b4feef9db6b1e0d95a6cca6de1a50db4de07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e50e02fb82233b04d0ef50ab6535208403e5033dc62bc7fc3b42c5297501d35c99ea5f8f4b9809f63b2192634a13bee92992787e314e736cce7e922f5a3ab4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d84adf49aea8a2ca3b8315361e966008b28f13e9bd81052637ab190893aef8500dacc4a92a9b0c3004440c4c757de907bd41cbe1cabb96cc0a2a8f350f7e67"} 08:06:43 INFO - Running post-run listener: copy_logs_to_upload_dir 08:06:43 INFO - Copying logs to upload dir... 08:06:43 INFO - mkdir: /builds/slave/test/build/upload/logs 08:06:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.140384 ========= master_lag: 0.79 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-08 08:06:44.795269) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-08 08:06:44.799209) ========= 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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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/Firefox/sha512/9384aced6efdc5dfa62dc663c353edc20734fc1b28edea3527ab5664eb8ce5a51be8f8bb2c194226054ef98a671b88e2fa2483e7f36700eb99e87aa6b657c521", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50877712797efec0113b73fba80609fd3f9764bbc190aa31a8d16886420775cf778d4f00083542b0dfe1a2f6332b4feef9db6b1e0d95a6cca6de1a50db4de07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e50e02fb82233b04d0ef50ab6535208403e5033dc62bc7fc3b42c5297501d35c99ea5f8f4b9809f63b2192634a13bee92992787e314e736cce7e922f5a3ab4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d84adf49aea8a2ca3b8315361e966008b28f13e9bd81052637ab190893aef8500dacc4a92a9b0c3004440c4c757de907bd41cbe1cabb96cc0a2a8f350f7e67"} build_url:https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011156 build_url: 'https://queue.taskcluster.net/v1/task/KOvYuZN8QUSxybaamGDmkg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9384aced6efdc5dfa62dc663c353edc20734fc1b28edea3527ab5664eb8ce5a51be8f8bb2c194226054ef98a671b88e2fa2483e7f36700eb99e87aa6b657c521", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50877712797efec0113b73fba80609fd3f9764bbc190aa31a8d16886420775cf778d4f00083542b0dfe1a2f6332b4feef9db6b1e0d95a6cca6de1a50db4de07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e50e02fb82233b04d0ef50ab6535208403e5033dc62bc7fc3b42c5297501d35c99ea5f8f4b9809f63b2192634a13bee92992787e314e736cce7e922f5a3ab4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00d84adf49aea8a2ca3b8315361e966008b28f13e9bd81052637ab190893aef8500dacc4a92a9b0c3004440c4c757de907bd41cbe1cabb96cc0a2a8f350f7e67"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-08 08:06:44.887836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 08:06:44.888172) ========= 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.Hwbuqrne4x/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.ZIUOWtoOPF/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.005358 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 08:06:44.915708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-08 08:06:44.916025) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-08 08:06:44.916331) ========= ========= Total master_lag: 0.96 =========