builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0375 starttime: 1483532748.25 results: success (0) buildid: 20170104030214 builduid: 09ccf1c21bbb405c8ec963d0ad5a51c1 revision: 57ac9f63fc6953f4efeb0cc84a60192d3721251f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.246085) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.246539) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.246816) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.297978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.298365) ========= 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.lqquICQLF0/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.TtJybmi269/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-04 04:25:48-- 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% 164M=0s 2017-01-04 04:25:48 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.303778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.626119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.626449) ========= 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.lqquICQLF0/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.TtJybmi269/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.025252 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:48.678414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 04:25:48.681790) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 57ac9f63fc6953f4efeb0cc84a60192d3721251f --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 57ac9f63fc6953f4efeb0cc84a60192d3721251f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lqquICQLF0/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.TtJybmi269/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-04 04:25:48,779 truncating revision to first 12 chars 2017-01-04 04:25:48,779 Setting DEBUG logging. 2017-01-04 04:25:48,779 attempt 1/10 2017-01-04 04:25:48,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/57ac9f63fc69?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 04:25:49,839 unpacking tar archive at: mozilla-central-57ac9f63fc69/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359470 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 04:25:50.067600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:50.067923) ========= script_repo_revision: 57ac9f63fc6953f4efeb0cc84a60192d3721251f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:50.068312) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:50.068591) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:50.088250) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-04 04:25:50.088587) ========= /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.lqquICQLF0/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.TtJybmi269/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:25:50 INFO - MultiFileLogger online at 20170104 04:25:50 in /builds/slave/test 04:25:50 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 04:25:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:25:50 INFO - {'append_to_log': False, 04:25:50 INFO - 'base_work_dir': '/builds/slave/test', 04:25:50 INFO - 'blob_upload_branch': 'Firefox', 04:25:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:25:50 INFO - 'branch': 'Firefox', 04:25:50 INFO - 'buildbot_json_path': 'buildprops.json', 04:25:50 INFO - 'config_files': ('talos/mac_config.py',), 04:25:50 INFO - 'default_actions': ('clobber', 04:25:50 INFO - 'read-buildbot-config', 04:25:50 INFO - 'download-and-extract', 04:25:50 INFO - 'populate-webroot', 04:25:50 INFO - 'create-virtualenv', 04:25:50 INFO - 'install', 04:25:50 INFO - 'run-tests'), 04:25:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:25:50 INFO - 'download_minidump_stackwalk': True, 04:25:50 INFO - 'download_symbols': 'ondemand', 04:25:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:25:50 INFO - 'tooltool.py': '/tools/tooltool.py', 04:25:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:25:50 INFO - '/tools/misc-python/virtualenv.py')}, 04:25:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:25:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:25:50 INFO - 'installer_path': 'installer.exe', 04:25:50 INFO - 'log_level': 'info', 04:25:50 INFO - 'log_name': 'talos', 04:25:50 INFO - 'log_to_console': True, 04:25:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:25:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:25:50 INFO - 'opt_config_files': (), 04:25:50 INFO - 'pip_index': False, 04:25:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:50 INFO - 'cmd': ('bash', 04:25:50 INFO - '-c', 04:25:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:50 INFO - 'enabled': True, 04:25:50 INFO - 'halt_on_failure': False, 04:25:50 INFO - 'name': 'check_screen_resolution'},), 04:25:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:50 INFO - 'cmd': ('bash', 04:25:50 INFO - '-c', 04:25:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:50 INFO - 'enabled': True, 04:25:50 INFO - 'halt_on_failure': False, 04:25:50 INFO - 'name': 'check_screen_resolution'},), 04:25:50 INFO - 'run_cmd_checks_enabled': True, 04:25:50 INFO - 'sps_profile': False, 04:25:50 INFO - 'sps_profile_interval': 0, 04:25:50 INFO - 'suite': 'dromaeojs-e10s', 04:25:50 INFO - 'system_bits': '32', 04:25:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:25:50 INFO - 'title': 't-yosemite-r7-0375', 04:25:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:25:50 INFO - 'use_talos_json': True, 04:25:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:25:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:25:50 INFO - 'work_dir': 'build'} 04:25:50 INFO - [mozharness: 2017-01-04 12:25:50.293922Z] Running clobber step. 04:25:50 INFO - Running pre-action listener: _resource_record_pre_action 04:25:50 INFO - Running main action method: clobber 04:25:50 INFO - rmtree: /builds/slave/test/build 04:25:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:25:52 INFO - Running post-action listener: _resource_record_post_action 04:25:52 INFO - [mozharness: 2017-01-04 12:25:52.635491Z] Finished clobber step (success) 04:25:52 INFO - [mozharness: 2017-01-04 12:25:52.635660Z] Running read-buildbot-config step. 04:25:52 INFO - Running pre-action listener: _resource_record_pre_action 04:25:52 INFO - Running main action method: read_buildbot_config 04:25:52 INFO - Using buildbot properties: 04:25:52 INFO - { 04:25:52 INFO - "project": "", 04:25:52 INFO - "product": "firefox", 04:25:52 INFO - "script_repo_revision": "production", 04:25:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:25:52 INFO - "repository": "", 04:25:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:25:52 INFO - "buildid": "20170104030214", 04:25:52 INFO - "stage_platform": "macosx64", 04:25:52 INFO - "basedir": "/builds/slave/test", 04:25:52 INFO - "nightly_build": "True", 04:25:52 INFO - "buildnumber": 4, 04:25:52 INFO - "slavename": "t-yosemite-r7-0375", 04:25:52 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:25:52 INFO - "platform": "yosemite_r7", 04:25:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:25:52 INFO - "branch": "mozilla-central", 04:25:52 INFO - "repo_path": "mozilla-central", 04:25:52 INFO - "revision": "57ac9f63fc6953f4efeb0cc84a60192d3721251f", 04:25:52 INFO - "pgo_build": "False", 04:25:52 INFO - "builduid": "09ccf1c21bbb405c8ec963d0ad5a51c1", 04:25:52 INFO - "slavebuilddir": "test" 04:25:52 INFO - } 04:25:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:25:52 INFO - Running post-action listener: _resource_record_post_action 04:25:52 INFO - [mozharness: 2017-01-04 12:25:52.639173Z] Finished read-buildbot-config step (success) 04:25:52 INFO - [mozharness: 2017-01-04 12:25:52.639287Z] Running download-and-extract step. 04:25:52 INFO - Running pre-action listener: _resource_record_pre_action 04:25:52 INFO - Running main action method: download_and_extract 04:25:52 INFO - mkdir: /builds/slave/test/build/tests 04:25:52 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')]} 04:25:52 INFO - trying https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:25:52 INFO - Downloading https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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 04:25:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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 04:25:53 INFO - Downloaded 1199 bytes. 04:25:53 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:25:53 INFO - Using the following test package requirements: 04:25:53 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:25:53 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:25:53 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:25:53 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'jsshell-mac.zip'], 04:25:53 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:25:53 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:25:53 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:25:53 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:25:53 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:25:53 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:53 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:25:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:25:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:25:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:25:53 INFO - Fetch https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:25:54 INFO - Expected file size: 16300572 04:25:54 INFO - Obtained file size: 16300572 04:26:01 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 04:26:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:26:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:26:01 INFO - Fetch https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:26:02 INFO - Expected file size: 16300572 04:26:02 INFO - Obtained file size: 16300572 04:26:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:26:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:26:08 INFO - Fetch https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:26:09 INFO - Expected file size: 14403730 04:26:09 INFO - Obtained file size: 14403730 04:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:09 INFO - trying https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:26:09 INFO - Downloading https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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 04:26:11 INFO - Downloaded 69746518 bytes. 04:26:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:26:11 INFO - mkdir: /builds/slave/test/properties 04:26:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:26:11 INFO - Writing to file /builds/slave/test/properties/build_url 04:26:11 INFO - Contents: 04:26:11 INFO - build_url:https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:26:12 INFO - Running post-action listener: _resource_record_post_action 04:26:12 INFO - Running post-action listener: set_extra_try_arguments 04:26:12 INFO - [mozharness: 2017-01-04 12:26:12.539726Z] Finished download-and-extract step (success) 04:26:12 INFO - [mozharness: 2017-01-04 12:26:12.539870Z] Running populate-webroot step. 04:26:12 INFO - Running pre-action listener: _resource_record_pre_action 04:26:12 INFO - Running main action method: populate_webroot 04:26:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:26:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:26:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:26:12 INFO - '--tppagecycles', 04:26:12 INFO - '7'], 04:26:12 INFO - 'tests': ['tsvgm']}}, 04:26:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:26:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:26:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:26:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:26:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:26:12 INFO - 'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:26:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:26:12 INFO - 'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['damp', 'tps']}, 04:26:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:26:12 INFO - 'tests': ['damp', 'tps']}, 04:26:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['dromaeo_dom']}, 04:26:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:26:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['basic_compositor_video']}, 04:26:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:26:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['a11yr', 04:26:12 INFO - 'ts_paint', 04:26:12 INFO - 'tpaint', 04:26:12 INFO - 'sessionrestore', 04:26:12 INFO - 'sessionrestore_no_auto_restore', 04:26:12 INFO - 'tabpaint']}, 04:26:12 INFO - 'other-e10s': {'tests': ['a11yr', 04:26:12 INFO - 'ts_paint', 04:26:12 INFO - 'tpaint', 04:26:12 INFO - 'sessionrestore', 04:26:12 INFO - 'sessionrestore_no_auto_restore', 04:26:12 INFO - 'tabpaint']}, 04:26:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:26:12 INFO - 'ts_paint', 04:26:12 INFO - 'tpaint', 04:26:12 INFO - 'sessionrestore', 04:26:12 INFO - 'sessionrestore_no_auto_restore', 04:26:12 INFO - 'tabpaint']}, 04:26:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:26:12 INFO - 'ts_paint', 04:26:12 INFO - 'tpaint', 04:26:12 INFO - 'sessionrestore', 04:26:12 INFO - 'sessionrestore_no_auto_restore', 04:26:12 INFO - 'tabpaint']}, 04:26:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['a11yr', 04:26:12 INFO - 'ts_paint', 04:26:12 INFO - 'tpaint', 04:26:12 INFO - 'sessionrestore', 04:26:12 INFO - 'sessionrestore_no_auto_restore', 04:26:12 INFO - 'tabpaint']}, 04:26:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['a11yr', 04:26:12 INFO - 'ts_paint', 04:26:12 INFO - 'tpaint', 04:26:12 INFO - 'sessionrestore', 04:26:12 INFO - 'sessionrestore_no_auto_restore', 04:26:12 INFO - 'tabpaint']}, 04:26:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['tsvgx', 04:26:12 INFO - 'tsvgr_opacity', 04:26:12 INFO - 'tart', 04:26:12 INFO - 'tscrollx', 04:26:12 INFO - 'cart', 04:26:12 INFO - 'tsvg_static']}, 04:26:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:26:12 INFO - 'tsvgr_opacity', 04:26:12 INFO - 'tart', 04:26:12 INFO - 'tscrollx', 04:26:12 INFO - 'cart', 04:26:12 INFO - 'tsvg_static']}, 04:26:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:26:12 INFO - 'talos_options': ['--disable-e10s'], 04:26:12 INFO - 'tests': ['tp5o']}, 04:26:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:26:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:26:12 INFO - 'talos_options': ['--disable-e10s', 04:26:12 INFO - '--xperf_path', 04:26:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:12 INFO - 'tests': ['tp5n']}, 04:26:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:26:12 INFO - 'talos_options': ['--xperf_path', 04:26:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:12 INFO - 'tests': ['tp5n']}}, 04:26:12 INFO - 'talos.zip': {'path': '', 04:26:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:26:12 INFO - Running post-action listener: _resource_record_post_action 04:26:12 INFO - [mozharness: 2017-01-04 12:26:12.558940Z] Finished populate-webroot step (success) 04:26:12 INFO - [mozharness: 2017-01-04 12:26:12.559055Z] Running create-virtualenv step. 04:26:12 INFO - Running pre-action listener: _resource_record_pre_action 04:26:12 INFO - Running main action method: create_virtualenv 04:26:12 INFO - Creating virtualenv /builds/slave/test/build/venv 04:26:12 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 04:26:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:26:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:26:12 INFO - Using real prefix '/tools/python27' 04:26:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:26:13 INFO - Installing distribute.............................................................................................................................................................................................done. 04:26:16 INFO - Installing pip.................done. 04:26:16 INFO - Return code: 0 04:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:26:16 INFO - Reading from file tmpfile_stdout 04:26:16 INFO - Output received: 04:26:16 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) 04:26:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:26: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')]} 04:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26: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')]} 04:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26: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', 'pip>=1.5'] in /builds/slave/test/build 04:26: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 pip>=1.5 04:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:16 INFO - 'HOME': '/Users/cltbld', 04:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:16 INFO - 'LOGNAME': 'cltbld', 04:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:16 INFO - 'MOZ_NO_REMOTE': '1', 04:26:16 INFO - 'NO_EM_RESTART': '1', 04:26:16 INFO - 'PAGER': '/bin/cat', 04:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:16 INFO - 'PWD': '/builds/slave/test', 04:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:16 INFO - 'SHELL': '/bin/bash', 04:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:16 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:16 INFO - 'USER': 'cltbld', 04:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:16 INFO - 'XPC_FLAGS': '0x0', 04:26:16 INFO - 'XPC_SERVICE_NAME': '0', 04:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:16 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 04:26:16 INFO - Cleaning up... 04:26:16 INFO - Return code: 0 04:26:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:26: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')]} 04:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26: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')]} 04:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26: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', '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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:26: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 psutil>=3.1.1 04:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:16 INFO - 'HOME': '/Users/cltbld', 04:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:16 INFO - 'LOGNAME': 'cltbld', 04:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:16 INFO - 'MOZ_NO_REMOTE': '1', 04:26:16 INFO - 'NO_EM_RESTART': '1', 04:26:16 INFO - 'PAGER': '/bin/cat', 04:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:16 INFO - 'PWD': '/builds/slave/test', 04:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:16 INFO - 'SHELL': '/bin/bash', 04:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:16 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:16 INFO - 'USER': 'cltbld', 04:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:16 INFO - 'XPC_FLAGS': '0x0', 04:26:16 INFO - 'XPC_SERVICE_NAME': '0', 04:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:17 INFO - Downloading/unpacking psutil>=3.1.1 04:26:17 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 04:26:17 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 04:26:17 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 04:26:17 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 04:26:17 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 04:26:17 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 04:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:26:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:26:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:26:20 INFO - Installing collected packages: psutil 04:26:20 INFO - Running setup.py install for psutil 04:26:20 INFO - building 'psutil._psutil_osx' extension 04:26:20 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 04:26:21 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 04:26:21 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 04:26:21 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 04:26:21 INFO - building 'psutil._psutil_posix' extension 04:26:21 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 04:26:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:26:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:26:21 INFO - ^ 04:26:21 INFO - 1 warning generated. 04:26:21 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 04:26:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:26:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:26:21 INFO - Successfully installed psutil 04:26:21 INFO - Cleaning up... 04:26:21 INFO - Return code: 0 04:26:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:21 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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:21 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 04:26:21 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 04:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:21 INFO - 'HOME': '/Users/cltbld', 04:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:21 INFO - 'LOGNAME': 'cltbld', 04:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:21 INFO - 'MOZ_NO_REMOTE': '1', 04:26:21 INFO - 'NO_EM_RESTART': '1', 04:26:21 INFO - 'PAGER': '/bin/cat', 04:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:21 INFO - 'PWD': '/builds/slave/test', 04:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:21 INFO - 'SHELL': '/bin/bash', 04:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:21 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:21 INFO - 'USER': 'cltbld', 04:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:21 INFO - 'XPC_FLAGS': '0x0', 04:26:21 INFO - 'XPC_SERVICE_NAME': '0', 04:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:26:22 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 04:26:22 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 04:26:22 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 04:26:22 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 04:26:22 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 04:26:22 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 04:26:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:26:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:26:24 INFO - Installing collected packages: mozsystemmonitor 04:26:24 INFO - Running setup.py install for mozsystemmonitor 04:26:24 INFO - Successfully installed mozsystemmonitor 04:26:24 INFO - Cleaning up... 04:26:25 INFO - Return code: 0 04:26:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:26: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')]} 04:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26: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')]} 04:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:25 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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:25 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 04:26:25 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 04:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:25 INFO - 'HOME': '/Users/cltbld', 04:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:25 INFO - 'LOGNAME': 'cltbld', 04:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:25 INFO - 'MOZ_NO_REMOTE': '1', 04:26:25 INFO - 'NO_EM_RESTART': '1', 04:26:25 INFO - 'PAGER': '/bin/cat', 04:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:25 INFO - 'PWD': '/builds/slave/test', 04:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:25 INFO - 'SHELL': '/bin/bash', 04:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:25 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:25 INFO - 'USER': 'cltbld', 04:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:25 INFO - 'XPC_FLAGS': '0x0', 04:26:25 INFO - 'XPC_SERVICE_NAME': '0', 04:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:25 INFO - Downloading/unpacking jsonschema==2.5.1 04:26:25 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 04:26:25 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 04:26:25 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 04:26:25 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 04:26:25 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 04:26:25 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 04:26:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:26:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:28 INFO - Downloading functools32-3.2.3-2.tar.gz 04:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:26:28 INFO - warning: no files found matching '*.txt' 04:26:28 INFO - no previously-included directories found matching 'build' 04:26:28 INFO - no previously-included directories found matching 'dist' 04:26:28 INFO - no previously-included directories found matching '.git*' 04:26:28 INFO - Installing collected packages: jsonschema, functools32 04:26:28 INFO - Running setup.py install for functools32 04:26:28 INFO - warning: no files found matching '*.txt' 04:26:28 INFO - no previously-included directories found matching 'build' 04:26:28 INFO - no previously-included directories found matching 'dist' 04:26:28 INFO - no previously-included directories found matching '.git*' 04:26:28 INFO - Successfully installed jsonschema functools32 04:26:28 INFO - Cleaning up... 04:26:28 INFO - Return code: 0 04:26:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:28 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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:28 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 04:26:28 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 04:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:28 INFO - 'HOME': '/Users/cltbld', 04:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:28 INFO - 'LOGNAME': 'cltbld', 04:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:28 INFO - 'MOZ_NO_REMOTE': '1', 04:26:28 INFO - 'NO_EM_RESTART': '1', 04:26:28 INFO - 'PAGER': '/bin/cat', 04:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:28 INFO - 'PWD': '/builds/slave/test', 04:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:28 INFO - 'SHELL': '/bin/bash', 04:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:28 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:28 INFO - 'USER': 'cltbld', 04:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:28 INFO - 'XPC_FLAGS': '0x0', 04:26:28 INFO - 'XPC_SERVICE_NAME': '0', 04:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:26:29 INFO - Cleaning up... 04:26:29 INFO - Return code: 0 04:26:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:26: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')]} 04:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26: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')]} 04:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:29 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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:29 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 04:26:29 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 04:26:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:29 INFO - 'HOME': '/Users/cltbld', 04:26:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:29 INFO - 'LOGNAME': 'cltbld', 04:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:29 INFO - 'MOZ_NO_REMOTE': '1', 04:26:29 INFO - 'NO_EM_RESTART': '1', 04:26:29 INFO - 'PAGER': '/bin/cat', 04:26:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:29 INFO - 'PWD': '/builds/slave/test', 04:26:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:29 INFO - 'SHELL': '/bin/bash', 04:26:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:29 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:29 INFO - 'USER': 'cltbld', 04:26:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:29 INFO - 'XPC_FLAGS': '0x0', 04:26:29 INFO - 'XPC_SERVICE_NAME': '0', 04:26:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:29 INFO - Downloading/unpacking blobuploader==1.2.4 04:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:31 INFO - Downloading blobuploader-1.2.4.tar.gz 04:26:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:26:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:26:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 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 04:26:32 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 04:26:33 INFO - Downloading docopt-0.6.1.tar.gz 04:26:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:26:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:26:33 INFO - Running setup.py install for blobuploader 04:26:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:26:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:26:33 INFO - Running setup.py install for requests 04:26:33 INFO - Running setup.py install for docopt 04:26:34 INFO - Successfully installed blobuploader requests docopt 04:26:34 INFO - Cleaning up... 04:26:34 INFO - Return code: 0 04:26:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:34 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')]} 04:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:34 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')]} 04:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:34 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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:34 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 04:26:34 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 04:26:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:34 INFO - 'HOME': '/Users/cltbld', 04:26:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:34 INFO - 'LOGNAME': 'cltbld', 04:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:34 INFO - 'MOZ_NO_REMOTE': '1', 04:26:34 INFO - 'NO_EM_RESTART': '1', 04:26:34 INFO - 'PAGER': '/bin/cat', 04:26:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:34 INFO - 'PWD': '/builds/slave/test', 04:26:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:34 INFO - 'SHELL': '/bin/bash', 04:26:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:34 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:34 INFO - 'USER': 'cltbld', 04:26:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:34 INFO - 'XPC_FLAGS': '0x0', 04:26:34 INFO - 'XPC_SERVICE_NAME': '0', 04:26:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-eF8Ijm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-38SzOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-0HaxEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8cO2Xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Ct77kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-cyKSgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-zVvyxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ce94pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-naAH9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-xxG74x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-wG3hMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ddU7Hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_aGcwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-32Ge6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-QEo8Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:26:36 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8E633O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:26:36 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-PG0gs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:26:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:26:36 INFO - Running setup.py install for manifestparser 04:26:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:26:36 INFO - Running setup.py install for mozcrash 04:26:36 INFO - Running setup.py install for mozdebug 04:26:36 INFO - Running setup.py install for mozdevice 04:26:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:26:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:26:36 INFO - Running setup.py install for mozfile 04:26:36 INFO - Running setup.py install for mozhttpd 04:26:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Running setup.py install for mozinfo 04:26:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Running setup.py install for mozInstall 04:26:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Running setup.py install for mozleak 04:26:37 INFO - Running setup.py install for mozlog 04:26:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Running setup.py install for moznetwork 04:26:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:26:37 INFO - Running setup.py install for mozprocess 04:26:38 INFO - Running setup.py install for mozprofile 04:26:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:26:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:26:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:26:38 INFO - Running setup.py install for mozrunner 04:26:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:26:38 INFO - Running setup.py install for mozscreenshot 04:26:38 INFO - Running setup.py install for moztest 04:26:38 INFO - Running setup.py install for mozversion 04:26:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:26:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:26:38 INFO - Cleaning up... 04:26:38 INFO - Return code: 0 04:26:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:38 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')]} 04:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:38 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')]} 04:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:38 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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:38 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 04:26:38 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 04:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:38 INFO - 'HOME': '/Users/cltbld', 04:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:38 INFO - 'LOGNAME': 'cltbld', 04:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:38 INFO - 'MOZ_NO_REMOTE': '1', 04:26:38 INFO - 'NO_EM_RESTART': '1', 04:26:38 INFO - 'PAGER': '/bin/cat', 04:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:38 INFO - 'PWD': '/builds/slave/test', 04:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:38 INFO - 'SHELL': '/bin/bash', 04:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:38 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:38 INFO - 'USER': 'cltbld', 04:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:38 INFO - 'XPC_FLAGS': '0x0', 04:26:38 INFO - 'XPC_SERVICE_NAME': '0', 04:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-lRiFbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-LVozeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-bEhC1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-5Nn9dR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Qd55a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-0lf5Wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-dDBf0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-oK1qb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:26:39 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)) 04:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:26:39 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-nfoAMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:26:40 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)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-LcJZcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:26:40 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)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-iUzUrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:26:40 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)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TLtYeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-42smjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:26:40 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)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-vEGWS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:26:40 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)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-frHi9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:40 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)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-BqxMmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:26:40 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)) 04:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:26:40 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-1FvLke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:26:40 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)) 04:26:40 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)) 04:26:40 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)) 04:26:40 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)) 04:26:40 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)) 04:26:40 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)) 04:26:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:26:40 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 04:26:40 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 04:26:40 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 04:26:40 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 04:26:40 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 04:26:40 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 04:26:43 INFO - Downloading blessings-1.6.tar.gz 04:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:26:43 INFO - Installing collected packages: blessings 04:26:43 INFO - Running setup.py install for blessings 04:26:43 INFO - Successfully installed blessings 04:26:43 INFO - Cleaning up... 04:26:43 INFO - Return code: 0 04:26:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:26:44 INFO - Reading from file tmpfile_stdout 04:26:44 INFO - Current package versions: 04:26:44 INFO - blessings == 1.6 04:26:44 INFO - blobuploader == 1.2.4 04:26:44 INFO - docopt == 0.6.1 04:26:44 INFO - functools32 == 3.2.3-2 04:26:44 INFO - jsonschema == 2.5.1 04:26:44 INFO - manifestparser == 1.1 04:26:44 INFO - mozInstall == 1.12 04:26:44 INFO - mozcrash == 1.0 04:26:44 INFO - mozdebug == 0.1 04:26:44 INFO - mozdevice == 0.49 04:26:44 INFO - mozfile == 1.2 04:26:44 INFO - mozhttpd == 0.7 04:26:44 INFO - mozinfo == 0.9 04:26:44 INFO - mozleak == 0.1 04:26:44 INFO - mozlog == 3.4 04:26:44 INFO - moznetwork == 0.27 04:26:44 INFO - mozprocess == 0.23 04:26:44 INFO - mozprofile == 0.28 04:26:44 INFO - mozrunner == 6.13 04:26:44 INFO - mozscreenshot == 0.1 04:26:44 INFO - mozsystemmonitor == 0.3 04:26:44 INFO - moztest == 0.8 04:26:44 INFO - mozversion == 1.4 04:26:44 INFO - psutil == 3.1.1 04:26:44 INFO - requests == 1.2.3 04:26:44 INFO - wsgiref == 0.1.2 04:26:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:44 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 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:44 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 04:26:44 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 04:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:44 INFO - 'HOME': '/Users/cltbld', 04:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:44 INFO - 'LOGNAME': 'cltbld', 04:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:44 INFO - 'MOZ_NO_REMOTE': '1', 04:26:44 INFO - 'NO_EM_RESTART': '1', 04:26:44 INFO - 'PAGER': '/bin/cat', 04:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:44 INFO - 'PWD': '/builds/slave/test', 04:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:44 INFO - 'SHELL': '/bin/bash', 04:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:44 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:44 INFO - 'USER': 'cltbld', 04:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:44 INFO - 'XPC_FLAGS': '0x0', 04:26:44 INFO - 'XPC_SERVICE_NAME': '0', 04:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:26:45 INFO - Cleaning up... 04:26:45 INFO - Return code: 0 04:26:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109237b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10923a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4bb45d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, '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.lqquICQLF0/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.TtJybmi269/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 04:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:26:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:26:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:45 INFO - 'HOME': '/Users/cltbld', 04:26:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:45 INFO - 'LOGNAME': 'cltbld', 04:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:45 INFO - 'MOZ_NO_REMOTE': '1', 04:26:45 INFO - 'NO_EM_RESTART': '1', 04:26:45 INFO - 'PAGER': '/bin/cat', 04:26:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:45 INFO - 'PWD': '/builds/slave/test', 04:26:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:45 INFO - 'SHELL': '/bin/bash', 04:26:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:26:45 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:26:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:45 INFO - 'USER': 'cltbld', 04:26:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:45 INFO - 'XPC_FLAGS': '0x0', 04:26:45 INFO - 'XPC_SERVICE_NAME': '0', 04:26:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:26:45 INFO - Cleaning up... 04:26:45 INFO - Return code: 0 04:26:45 INFO - Running post-action listener: _resource_record_post_action 04:26:45 INFO - Running post-action listener: _start_resource_monitoring 04:26:45 INFO - Starting resource monitoring. 04:26:45 INFO - [mozharness: 2017-01-04 12:26:45.857080Z] Finished create-virtualenv step (success) 04:26:45 INFO - [mozharness: 2017-01-04 12:26:45.857962Z] Running install step. 04:26:45 INFO - Running pre-action listener: _resource_record_pre_action 04:26:45 INFO - Running main action method: install 04:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:26:46 INFO - Reading from file tmpfile_stdout 04:26:46 INFO - Detecting whether we're running mozinstall >=1.0... 04:26:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:26:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:26:46 INFO - Reading from file tmpfile_stdout 04:26:46 INFO - Output received: 04:26:46 INFO - Usage: mozinstall [options] installer 04:26:46 INFO - Options: 04:26:46 INFO - -h, --help show this help message and exit 04:26:46 INFO - -d DEST, --destination=DEST 04:26:46 INFO - Directory to install application into. [default: 04:26:46 INFO - "/builds/slave/test"] 04:26:46 INFO - --app=APP Application being installed. [default: firefox] 04:26:46 INFO - mkdir: /builds/slave/test/build/application 04:26:46 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'] 04:26:46 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 04:27:07 INFO - Reading from file tmpfile_stdout 04:27:07 INFO - Output received: 04:27:07 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:27:07 INFO - Running post-action listener: _resource_record_post_action 04:27:07 INFO - [mozharness: 2017-01-04 12:27:07.963854Z] Finished install step (success) 04:27:07 INFO - [mozharness: 2017-01-04 12:27:07.963968Z] Running run-tests step. 04:27:07 INFO - Running pre-action listener: _resource_record_pre_action 04:27:07 INFO - Running main action method: run_tests 04:27:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:27:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:27:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:27:07 INFO - 2017-01-04 04:27:07.978 screenresolution[1628:7443] starting screenresolution argv=screenresolution get 04:27:07 INFO - 2017-01-04 04:27:07.980 screenresolution[1628:7443] Display 0: 1600x1200x32@60 04:27:07 INFO - 2017-01-04 04:27:07.986 screenresolution[1629:7445] starting screenresolution argv=screenresolution list 04:27:07 INFO - Available Modes on Display 0 04:27:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:27:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:27:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:27:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:27:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:27:08 INFO - Intel Iris: 04:27:08 INFO - Chipset Model: Intel Iris 04:27:08 INFO - Type: GPU 04:27:08 INFO - Bus: Built-In 04:27:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:27:08 INFO - Vendor: Intel (0x8086) 04:27:08 INFO - Device ID: 0x0a2e 04:27:08 INFO - Revision ID: 0x0009 04:27:08 INFO - Displays: 04:27:08 INFO - Display: 04:27:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:27:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:27:08 INFO - Display Serial Number: 1600x1200 60 04:27:08 INFO - Main Display: Yes 04:27:08 INFO - Mirror: Off 04:27:08 INFO - Online: Yes 04:27:08 INFO - Rotation: Supported 04:27:08 INFO - Return code: 0 04:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:27:08 INFO - Python 2.7.3 04:27:08 INFO - Return code: 0 04:27:08 INFO - grabbing minidump binary from tooltool 04:27:08 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')]} 04:27:08 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 0x109015d30>, '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 0x108f3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10900abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:27:08 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 04:27:08 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 04:27:08 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 04:27:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:27:08 INFO - Return code: 0 04:27:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:27:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:27:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:27:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:27:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:27:08 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 04:27:08 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --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 04:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqquICQLF0/Render', 04:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:08 INFO - 'HOME': '/Users/cltbld', 04:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:08 INFO - 'LOGNAME': 'cltbld', 04:27:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:27:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:08 INFO - 'MOZ_NO_REMOTE': '1', 04:27:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:27:08 INFO - 'NO_EM_RESTART': '1', 04:27:08 INFO - 'PAGER': '/bin/cat', 04:27:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:08 INFO - 'PWD': '/builds/slave/test', 04:27:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:08 INFO - 'SHELL': '/bin/bash', 04:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TtJybmi269/Listeners', 04:27:08 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 04:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:08 INFO - 'USER': 'cltbld', 04:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:08 INFO - 'XPC_FLAGS': '0x0', 04:27:08 INFO - 'XPC_SERVICE_NAME': '0', 04:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:08 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 04:27:08 INFO - mozversion application_buildid: 20170104030214 04:27:08 INFO - mozversion application_changeset: 57ac9f63fc6953f4efeb0cc84a60192d3721251f 04:27:08 INFO - mozversion application_display_name: Nightly 04:27:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:27:08 INFO - mozversion application_name: Firefox 04:27:08 INFO - mozversion application_remotingname: firefox 04:27:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:27:08 INFO - mozversion application_vendor: Mozilla 04:27:08 INFO - mozversion application_version: 53.0a1 04:27:08 INFO - mozversion platform_buildid: 20170104030214 04:27:08 INFO - mozversion platform_changeset: 57ac9f63fc6953f4efeb0cc84a60192d3721251f 04:27:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:27:08 INFO - mozversion platform_version: 53.0a1 04:27:08 INFO - using testdate: 1483532828 04:27:08 INFO - actual date: 1483532828 04:27:08 INFO - starting webserver on 'localhost:49248' 04:27:08 INFO - SUITE-START | Running 2 tests 04:27:08 INFO - TEST-START | dromaeo_css 04:27:08 INFO - operating with platform_type : mac_ 04:27:08 INFO - Initialising browser for dromaeo_css test... 04:27:08 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp5mNdEy/profile http://localhost:49248/getInfo.html) 04:27:09 INFO - PROCESS | 1639 | 1483532829219 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/FirefoxNightly.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/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4901 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3756 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2789 < callProvider()@resource://gre/modules/AddonManager.jsm:260 < _startProvider()@resource://gre/modules/AddonManager.jsm:755 < startup()@resource://gre/modules/AddonManager.jsm:937 < startup()@resource://gre/modules/AddonManager.jsm:2886 < observe()@resource://gre/components/addonManager.js:65 04:27:11 INFO - PROCESS | 1639 | 2017-01-04 04:27:11.615 plugin-container[1642:7613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4f, name = 'com.apple.tsm.portname' 04:27:11 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:27:11 INFO - PROCESS | 1639 | 2017-01-04 04:27:11.616 plugin-container[1642:7613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x951b, name = 'com.apple.CFPasteboardClient' 04:27:11 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:27:11 INFO - PROCESS | 1639 | 2017-01-04 04:27:11.616 plugin-container[1642:7613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:27:11 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 04:27:11 INFO - PROCESS | 1639 | colorDepth:24 04:27:11 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 04:27:11 INFO - PROCESS | 1639 | __metrics 04:27:11 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 04:27:12 INFO - TEST-INFO | 1639: exit 0 04:27:12 INFO - Browser initialized. 04:27:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:27:12 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp5mNdEy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:27:15 INFO - PROCESS | 1643 | 2017-01-04 04:27:15.109 plugin-container[1644:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 04:27:15 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:27:15 INFO - PROCESS | 1643 | 2017-01-04 04:27:15.110 plugin-container[1644:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b1f, name = 'com.apple.CFPasteboardClient' 04:27:15 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:27:15 INFO - PROCESS | 1643 | 2017-01-04 04:27:15.110 plugin-container[1644:7737] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:27:24 INFO - PROCESS | 1643 | RSS: Main: 232468480 04:27:24 INFO - PROCESS | 1643 | 04:28:14 INFO - PROCESS | 1643 | 1483532894379 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:30:27 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 04:30:29 INFO - PROCESS | 1643 | RSS: Main: 259211264 04:30:29 INFO - PROCESS | 1643 | 04:32:12 INFO - PROCESS | 1643 | ************************* 04:32:12 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 04:32:12 INFO - PROCESS | 1643 | 04:32:12 INFO - PROCESS | 1643 | Full message: ReferenceError: fetch is not defined 04:32:12 INFO - PROCESS | 1643 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:32:12 INFO - PROCESS | 1643 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:32:12 INFO - PROCESS | 1643 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:32:12 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:32:12 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:32:12 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:32:12 INFO - PROCESS | 1643 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:32:12 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:32:12 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:32:12 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:32:12 INFO - PROCESS | 1643 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:32:12 INFO - PROCESS | 1643 | 04:32:12 INFO - PROCESS | 1643 | ************************* 04:33:32 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 04:33:32 INFO - PROCESS | 1643 | RSS: Main: 260243456 04:33:32 INFO - PROCESS | 1643 | 04:36:34 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 04:36:35 INFO - PROCESS | 1643 | RSS: Main: 268783616 04:36:35 INFO - PROCESS | 1643 | 04:39:37 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 04:39:37 INFO - PROCESS | 1643 | RSS: Main: 265912320 04:39:37 INFO - PROCESS | 1643 | 04:42:40 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 04:42:41 INFO - PROCESS | 1643 | RSS: Main: 270458880 04:42:41 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 04:45:44 INFO - PROCESS | 1643 | RSS: Main: 273137664 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | __start_tp_report 04:45:44 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 04:45:44 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 04:45:44 INFO - PROCESS | 1643 | |i|pagename|runs| 04:45:44 INFO - PROCESS | 1643 | |0;dojo.html;2712.891484340626;3322.601287845459;3323.6842499962495;3333.1833700743323;3347.079553122891;5173.844784656461;5484.314460692413;5584.664920104797;5651.547876169907;5676.233708449358;4761.785744635114;5413.593980451472;5514.5864060195445;5544.417836127199;5553;5008.499150084999;5025.070361983045;5071.442141364447;5121.590272778169;5126.794928202868;4436.489803672571;5083.110455670243;5131.897362052757;5133.614978876581;5240.196469930515;4974.577160941288;5136.289716976926;5158.252053452247;5182.067227898977;5189.688618682872;5022.221555658879;5089.007643509517;5094.184930411154;5098.694078355274;5099.541041306263;4970.676906001098;5000.349954505891;5041.0422019816115;5092.312537807423;5101.846944591668;4036;5477.507024367826;5535.501804837584;5583.302087239095;5627.437256274381;5888.116782482619;6060.909086363708;6068.878622427571;6073.605215661012;6076.5442591805795;6005.4385859676795;6106.2977757558;6108.938910610926;6122.785702500391;6128.846778830521;5561.304836895388;5638.4361563843695;5641.887162256732;5644.774209031634;5654.180143879114;6112.480439162632;6138.878900475427;6139.737467782837;6145.525073982212;6158.784442544489;5936.406359364029;5938.554608404387;5940.198073260054;5945.256842894638;5949.3753155918;6041.818745437644;6056.424639659225;6081.324217344354;6091.238595175603;6107.236595425572;5913.615614985012;5941.792037278675;6008.579399441997;6069.848253793691;6077.726502307407;6018.638881667026;6021.819345419644;6022.277326720821;6052.878942421127;6054.152418661391;4819.228923372244;4823.27650852375;4830.203016502308;4850.684705494132;4867.975660121677;4237;4249.617534421855;4282.015136518618;4343.522212556616;4345.630621397154;5241.764120614582;5577.609567330248;5579.721013949367;5664.037113690682;5676.120201368794;5201;6100.603460775035;6166.845828854225;6207.6275423474735;6227.595206311576;5712.914306285326;5715.714214289269;5716.1997320375185;5729.083346664514;5747.3677895431465;5163.122269214243;5172.637915345965;5230.293910322134;5276;5293.338332708411;6163.445289923929;6178.196834411498;6194.845128871724;6210.596311239755;6260.154879091447;6123.663198524124;6173.47525460323;6194.0708893666315;6228.252609686867;6238.75044998195;5950;6220.720067596879;6229.875402492015;6244.937550624617;6248.094026366242;4215.957840421556;5923.437273459014;5945;6008.068749343859;6036.63780173191;5836.941630583639;6019;6043.607165534227;6075.574709770273;6128.540359473147;5079.974600126976;5671.602987790815;5869.618474799125;5893.233879595625;5944;5773.6824474654295;5902.704864756831;5911.083782013795;5911.7635294587735;5922.644641321534;5687.60186786921;5694.402087780723;5709.34342550612;5763.798267060801;5785.739641716049;5826.621269617462;5839.445252700986;5869.442402971711;5877.265341832271;5887.970560147171;5716.657000579979;5751.223584815996;5754.395788442153;5769.480746732815;5784.1034639630925 04:45:44 INFO - PROCESS | 1643 | |1;ext.html;451.95706407891237;494.6339708615623;494.6735154797833;512.0169274992015;534.6043927493654;37308;39255.60744392555;39682.80158599206;41606.58393416065;41779;40633.39049914254;40885.59114408855;41025.794871025675;41027.79486102573;41048;8243.010838699365;9099.272058235341;9261.305402094837;9310.208632266265;9327.533623318841;8349.874751878726;9924.45415502147;10532.210084243674;10890;11300.208985371028;11169.888301116966;11210.719732006684;11212.990830825265;11223.887761122365;11268.605598804044;11064.834027489595;11135.665930022113;11155.219134660578;11191.104711623051;11221.495032723466;11063.72340691481;11134.832977505344;11148.21962462628;11161.38612376319;11164.330140191614;38375.42436863449;38505;38681.80659096715;38834.02914927121;38898.61101388978;3749.212665340282;3976.76139431635;3992.5807790181902;3995.9400608990886;4000.3599424092295;29652.258693532618;29830.806767729264;30044.54933176004;30136.54795178074;30172.396552069054;3003.8498075096377;3073.4928736758407;3203.2952750394875;3275.836208189605;3285.8192799396024;328543;335941.32029339694;346476.5352346495;364132.3586764125;366013;50711.49288507141;57379;57431.425685743445;57593;57611.71194144002;48767.75616121897;60226.69886650539;60548;60551.69724151351;60666.696666516385;15408.460703875311;15577.922110389602;15589.76615350748;15594.064356138668;15595.766063509056;10915.072218861327;11147.72130696739;12005.939970300267;12040.638780836589;12067.517299308105;15077.773833392508;15348.539543813704;15385.461508847136;15527.689446211012;15667.686646267011;6024.27708674953;6079;6096.786612468542;6099.176611157437;6135.754569817157;727.7198278662647;843.8270154618411;861.5778268648443;863.1541089731973;865.5931712095305;24343.513129737305;25295.494090118096;27484;27956.301092472444;28540.857295713387;989.1749721763335;994.8010397920444;1013.7212266626736;1024.2778840917135;1172.9824052639217;13649.181049137083;13876.58370248894;13907.930460347634;14024.298785060706;14034.508792192224;618125;619957;622008;622327;629714;48742;48816.77958051006;48879;48990;48999.75500122548;1335.2789493673308;1369.5206677662939;1381.4819442708783;1394.17743531317;1396.9371378288001;24722.516649001118;25245.990160394114;25934.610980834925;26258.86870565635;26393;32081;42800.78599606982;42825.785871070446;42879.3568096485;42955.78522107495;525122;526561;527073;527348;527801;23218.53562928698;23429.882850585636;23533;23934.88032559826;24032.39919002039;33368.83315583504;35027.649723503455;36135.45796812997;40995;41238;33154.834225828716;34742;35586.64413355937;36061.639383606875;36083;33724;34168.31663366769;34414.48378274278;35126.64873351234;35225.11937201488;33957.8114765982;34098.14754631154;34283.14292142716;34359.31281374308;34387.82806085954;24652.38369040788;29823.254418639706;30704.846475768372;30884.84557577198;31423.05730828079;21972.230971915906;23656.526869462858;23881.283561493183;23958.52082958296;23975.76024239735 04:45:44 INFO - PROCESS | 1643 | |2;jquery.html;378.29070492825934;384.1989451992596;388.615270881827;397.7613431940838;411.1920077048599;17971.10144492775;19657.01714914252;20200;20414.489637759045;20453.590928181427;19362.70955935662;19639;19825.603487930268;20141.892195929217;20188.29340973066;4967.552920237178;5432.348118225818;5643.322801263854;5895.145203945425;5930.080837470199;4584.876705207213;4689.507601701824;4974.88065185334;5631.577631677641;5709.143628455744;5536.833894983157;5691.800786972457;5742.770289188448;5799.217105690741;5800.187973683687;5720.227769251161;5788.247527821399;5788.681622510781;5792.362840087587;5801.419858014207;4921.827736029221;5003.124453220708;5083.440821509631;5085.338905942241;5164.6642968207325;23759.762402375927;24042.39894002664;24207.394815129588;24861.502769944498;24880.87559562209;2599.89798495526;2775.5559110542417;2797.692253852075;2809.4802461544677;2824.802263841532;9888.912219655833;15498.922505387514;15502.922485387613;15719.449819256386;15755.369785208577;1535.5239875638476;1787.517370310022;1829.9940487998465;1833.825786550289;1864.4127099963468;111631;113633.4318328403;113786;113864.99999999917;114993;11361;11397.943010284895;11488.54045838157;11495.827562586568;11696.824547631792;14211.365692945443;14366.712665746627;14416.062955907832;14456.927715361357;14459.78310325346;8297.502149870907;8472.406931514735;8484.830303393897;8495;8564.3148548116;8266.421350505407;8301.958490207511;8430.199131082538;8455.239028487342;8538.359623028298;6336.556441049082;6391.360863913665;7151.678174482161;7168.569885806868;7175.210726820045;1856.378113332022;1892.810718928096;1896.535348839543;1896.8577356698304;1900.353879680915;910.8841668955608;981.9803603928025;983.6311383231289;998.2662742883974;1036.1555332404066;20884.26905058346;20901.02880452709;21014.640857813705;21019.474513137295;21198.576028479343;1011.3780025284398;1023.5087158164123;1042.7653777900064;1048.2452634103433;1061.6125114333367;10387.324823886574;11204.60783872577;11230.494627741771;11233.213675042833;11244.987951084442;468820;542548;544272;547866;551733;129558.35220823836;139536;140307;140891;141481.29259353637;965.7633879699378;1003.844404117363;1014.60937539048;1019.6788011776267;1026.3687831983277;13055;14246.717795398465;16080.035197888164;19595.431265911793;20061.398158055283;36482;43309.91725206895;49857.753556161384;54604;54851.80592776244;75761;75950.480990381;76462.61768691121;76683.61658191674;77439;7308.634568271459;7559.773206803805;8291.543965081737;8294.502329860228;8696.869546956674;11238.438078095898;11647.242929209575;12813.679658008625;12848.036397270429;13110.606681799562;9654.951725241328;10106.696799095746;10351.378917264989;11030.944845275722;11150.386728729673;10958.616448424267;11081;11142.27575207609;11257.437128143396;11333.489992950172;11229.49467273991;11638.41807909618;11642.88357116418;11671.941640292083;11672.358020308624;7425.517341372793;7581.241875812375;7653.464257501922;7708.691652333844;7811.218878112143;10686.0916822071;10828.5127169276;10941;11159.330440173615;11533.88466115328 04:45:44 INFO - PROCESS | 1643 | |3;mootools.html;1424.7150569886026;1429.0782445322768;1429.5425463851566;1436.2603259321445;1450.144414795271;7442.330190282873;7752.147263800977;8207.138250483702;8490.830183396336;8526.872096918543;7939.523628582289;8236.517426863162;8247.927769389986;8357.373197010218;8368.0376756673;2268.319504148753;2310.9422264443438;2327.290176496168;2334.2180369576213;2340.1341503643675;2030.5431277962414;2105.673620588811;2302.4243939015246;2327.1505900346338;2349.8590084594894;2218.267971569378;2289.3933107726466;2301.4821665125382;2308.0190918859503;2309.4226443389152;2306.2965795432387;2314.375118717945;2322.674825524428;2334.2413715542516;2339.099446713019;2272.397814579138;2305.13557415969;2331.603627383349;2336.6728657987896;2356.8939397727145;8325.542095184763;8383;8424.326053915733;8435.325173986128;8463.322934165251;5066.594672426234;5233.581313494911;5237.869053273661;5244.370675518962;5264.710440925747;3344.147242453159;3534.3108093921696;3594.137407022322;3602.7117830573493;3607.2965771674535;1175.4416652090274;1212.345333519907;1231.359692959665;1254.0845183016359;1260.3887114749339;9104;9157.267418606496;10612.46937653122;10749.193810464169;10762.139028877671;3522.806245656462;3957.5448823385104;4143.3370660694145;4651.418572678415;4714.481407045222;9393.90606093944;9475.24198064167;9540.856887146698;9551.713448596553;9591.088846559565;5242.266082748418;5243.895122097537;5256.737163141827;5286.233496143038;5293.841184764463;4593.356930029799;4692.507286734844;4775.832845850378;4848.5636292733825;4859.125357435695;3756.924861502809;3781.962180378216;3850.942235866464;3882.6216693074116;3905.33609286422;972.3485264872552;1199.166579227446;1225.6629426907493;1256.824044633752;1269.9555515557097;835.6824406725406;839.0686338164633;868.2836659755727;897.9865302020474;898.7528429681889;4946.441870810683;5300.443453437333;5377.220303056035;5389.649672771257;5552.555795536428;802.3781569283851;941.538646063424;973.73709098543;988.8318985772436;1025.0620682075967;2650.5759078547726;3565.679088881961;3568.286342731464;3656.232191239816;3671.3391589514144;9580.808383832284;9674.854877176847;9841.60633574649;9887.357321774061;9950.552225149886;7360.558366498028;8703.912010998625;9642.373245739005;9813.754656133653;9875.012498750068;6794.558353706993;7564.130125035581;7655.999999999889;7693.192214817475;7785.80535486606;4595.885102872455;5350.491703288181;5352.652077614943;5382.596305277199;5442;8845.955770221108;9089.181973622406;9099.59051842682;9206.723798286062;9328.673496427593;9197.17225449686;9202.861957070783;9306.488143152192;9360.15758581731;9403.200727938201;6972.860542789217;7056.541324814076;7091.042709234393;7169.4622903281415;7187.9640601796655;6863.284178955261;7760;7768.378529717722;7768.417368697484;7790.88313675284;6998.720051197895;7088.964555177191;7104;7113.03973963529;7225.747098851515;6909.205441374105;6916.20463646687;6916.377526022682;6931.133608298963;6978;6021.096835474538;6223.657698826608;6278.591891527036;6318.08387783778;6326.493880489457;3927.941080883732;4542.227821270326;4569.2232320506255;4613.677042606985;4685.6017238535105;4092.447519584818;4256.254496043462;4669.789859456265;4674.5091765365505;4718.245080787057 04:45:44 INFO - PROCESS | 1643 | |4;prototype.html;332.0006779593423;356.0653285126542;362.50699049292956;364.81029864470474;370.2871971454949;4609.907801843965;5102.902875881685;5511.641743286684;5515.917261241075;5536.252605898202;4530.7281563106235;5399.83800485986;5413.972930135354;5431.293931788872;5449.975263728629;3597.2985267872778;3976.7216294859372;4145.626893579592;4196.517400498951;4204.915901681965;4225.704200705952;4228.5137209220875;4239.554846741079;4259.212045771542;4262.424572682679;3884.8834534964;3892.9805350973206;3938.901527461822;4225.535191128974;4261.190373828963;4066.593340665939;4166.416701661739;4234.301340278866;4243.111815242219;4247.362895565674;3651.8174091295327;3708.147126160998;3712.5706602957785;3715.2755212733528;3733.869314574004;4313.741175529478;4888.853334399938;5009.724465154415;5251.658642188246;5278;4735.266033764772;4862.3922009748785;4922.680025798348;4930.876728081791;4996.575291100261;4528.547145285478;5240.737963101829;5528.308961379827;5715.856828634248;5745.942540574582;3282.3763484937786;3876.5735769065664;3881.7679834095225;3903.4925459690057;3910.354791459406;4484.8206071757095;5309.973450132725;5441.918371224435;5855.795047173328;6017.398260173947;3493.877714279988;3504.1940353718787;3724.7522080102926;3769.1142581493327;3897.7466464679705;3696.464012718141;3758.9060273493383;3961.7821019844187;3986.80065996699;4007.6793856491413;4640.373549570832;4647.257278520555;4680.9297860532115;4682.836100736458;4705.717656940595;5478.041342765;5704.686242256633;5746.856328591819;5761.740721667535;5822.73797679097;1112.6105862947904;1135.7614900870744;1208.6313674329238;1287.7682017236987;1288.891553264192;736.7642354446633;857.8884744983112;862.6808081010066;883.7583195610177;885.6545947080643;4538.659600529973;4794.232922732346;4806.351142595775;4995.325631039836;4998.9500104999215;4951.133551628523;4991.028543725208;5006.923511445057;5018.035751756145;5381.515663590218;4101.671866250693;5118.488151184852;5135.743212839343;5141.794328226903;5261.842144735664;2514.559952008428;2668.3996100877534;2954.719301666338;2968.376640905391;2979.9702002979693;5122.436531981479;5429.534025813008;5614.24207731951;5654.717264136777;5844.327902291206;5349.576271186444;5475.890482190334;5781.82654520365;5837.474627283565;5852.941470585325;4978.800847966041;5381.354237491526;5534.584906132137;5754.338251101177;5910.379410161957;4941.703497790144;5015.448300686849;5125.051865404912;5391.973040134774;5718.828435146952;4914.852554423373;5337.359516858002;5436.266104075899;5592.440755924375;5607.747651355618;5127.436131979782;5440.29276194108;5530.972345138249;5689.260396148474;5870.589058766016;3894.6884249259424;3901.9414708778822;3931.6803086179293;3935.311320518955;3966.2067586483395;5026.899462010666;5838.970805145947;5893.145493903446;5926.525877929669;5975.7012149391485;5446.537044351275;5664.915026274692;5678.403767604507;5694.515966142924;5702.173184888251;4728.432588089451;4973.751312434291;5388.488093631099;5437.8368648940595;5632.901584191043;4782.282657601388;5349.97325013388;5375.19372094189;5595.468430499096;5619.971900140473;4674.883127921829;5655.321361436654;5670.8015219467125;5674.687892165971;5689.886202276014;4581.541845815392;5546.4453554644215;5584.581156413204;5611.551075913999;5634.464725851038 04:45:44 INFO - PROCESS | 1643 | |5;yui.html;705.5272967112035;762.3748526208508;775.5424299663198;799.2886331165262;812.2770734046695;15151.39394424223;15659.530214093567;15666;15701.214939253034;15729.921350393246;14357.641058973531;14498.927505362484;14528.927355363208;14544.418223271057;14556.854431455682;678.8947713104477;707.1266985273184;830.1739768929923;856.5203486047802;867.3364875869964;848.855694531928;869.5478351257374;872.9786150204244;873.9905409252324;875.6672464463495;924.8797656304705;927.3137877970321;928.0209379105041;928.3640706116332;930.2511478259999;912.0286894457381;918.42598376015;919.2599957034585;922.0594993107027;922.6217250927087;685.807973767341;689.1351354050685;690.4959379652831;691.401937324216;692.0726226856037;14690.412383504647;14898.851011489854;14967.700645987019;15052.774208386883;15512.922435387864;14131.858681413158;14451.78322325166;14468.131912085308;14478.493252736103;14502.129872207595;11752.294862308288;11837.467313970807;11864.288142711379;11948.761024779456;12227.348679452381;3687.8893633191046;3699.6670299672887;3718.215256676783;3728.6644202021675;3853.8843834685003;107435;107688.46155769171;108106;108769;108946;10878.619248326271;11092.944535277273;11193.888061119285;11212.327260364404;11229.831552526719;22929.853507324566;22939.426514337276;22953.885230573742;22990.195343162908;23012.964416601295;7597.468177227541;7668.424868134912;7778.727744529027;7815.25755053269;7990.64042118106;4195.119025004722;4220.725652832556;4308.052228509723;4321.481422229353;4398.120375924827;3431.9828400857837;3444.689977902001;3521.3837578423672;3526.4886591444097;3528.9942366425958;553.92522009529;619.3558698953038;644.3073695777058;648.6691787188568;660.96364699941;551.7131091832225;657.2572992518424;673.2661399075032;673.3569441183657;680.1532092544813;8854.77863053416;9244.306676999251;9472.591753939061;9477.573509191965;9860.753481162885;634.520936692794;658.8781075501048;675.2774531251612;681.9590824550543;693;4460.933086003648;4571.542845715402;4583.931241031387;4625.514320996247;4634.676941610993;85615;94985;104527.95472045182;106386;107463.46268268609;66569;92283;93507;94399;94899.99999999862;80069.59965200137;80802;81106;82388.352232955;83929.16070839214;7478.2521747824785;8975.685850995184;9000.54997250148;9005.189532942066;9181.989981102208;51255.74372128116;51647.483525164265;51727.48272517227;52935;54072.72963635314;69085.65457172682;71644.28355716376;71994.64002679953;72181;72253.63873180811;10295.7426064346;11758.882411175779;11923.376857091425;11939.388182595585;12291.631251062483;12646.557370491988;12815.44876014949;12907.161034532724;12972.481100755864;13234.933825330812;11925.761484770082;12084.758304833678;12153.197219636782;12176.512939482322;12479.251244925334;10594.682159535227;11811.881881181422;11896;11954;12098.334591597546;11825.669285846023;11846.466908988943;12043.156979011384;12099.33453660057;12474;7927.682892684228;9657.275704322063;9992.15066719337;10262.384256944608;10748.462576871281;7196.676149573386;8793.560321983747;8909.242714369351;8945.37382383227;9043 04:45:44 INFO - PROCESS | 1643 | __end_tp_report 04:45:44 INFO - PROCESS | 1643 | __start_cc_report 04:45:44 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,391 04:45:44 INFO - PROCESS | 1643 | __end_cc_report 04:45:44 INFO - PROCESS | 1643 | __startTimestamp1483533944563__endTimestamp 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | ------- Summary: start ------- 04:45:44 INFO - PROCESS | 1643 | Number of tests: 6 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5556.19 Median:5747.37 stddev:658.02 (11.4%) stddev-sans-first:621.09 04:45:44 INFO - PROCESS | 1643 | Values: 2712.9 3322.6 3323.7 3333.2 3347.1 5173.8 5484.3 5584.7 5651.5 5676.2 4761.8 5413.6 5514.6 5544.4 5553.0 5008.5 5025.1 5071.4 5121.6 5126.8 4436.5 5083.1 5131.9 5133.6 5240.2 4974.6 5136.3 5158.3 5182.1 5189.7 5022.2 5089.0 5094.2 5098.7 5099.5 4970.7 5000.3 5041.0 5092.3 5101.8 4036.0 5477.5 5535.5 5583.3 5627.4 5888.1 6060.9 6068.9 6073.6 6076.5 6005.4 6106.3 6108.9 6122.8 6128.8 5561.3 5638.4 5641.9 5644.8 5654.2 6112.5 6138.9 6139.7 6145.5 6158.8 5936.4 5938.6 5940.2 5945.3 5949.4 6041.8 6056.4 6081.3 6091.2 6107.2 5913.6 5941.8 6008.6 6069.8 6077.7 6018.6 6021.8 6022.3 6052.9 6054.2 4819.2 4823.3 4830.2 4850.7 4868.0 4237.0 4249.6 4282.0 4343.5 4345.6 5241.8 5577.6 5579.7 5664.0 5676.1 5201.0 6100.6 6166.8 6207.6 6227.6 5712.9 5715.7 5716.2 5729.1 5747.4 5163.1 5172.6 5230.3 5276.0 5293.3 6163.4 6178.2 6194.8 6210.6 6260.2 6123.7 6173.5 6194.1 6228.3 6238.8 5950.0 6220.7 6229.9 6244.9 6248.1 4216.0 5923.4 5945.0 6008.1 6036.6 5836.9 6019.0 6043.6 6075.6 6128.5 5080.0 5671.6 5869.6 5893.2 5944.0 5773.7 5902.7 5911.1 5911.8 5922.6 5687.6 5694.4 5709.3 5763.8 5785.7 5826.6 5839.4 5869.4 5877.3 5888.0 5716.7 5751.2 5754.4 5769.5 5784.1 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:62423.89 Median:34321.23 stddev:137030.05 (399.3%) stddev-sans-first:137335.46 04:45:44 INFO - PROCESS | 1643 | Values: 452.0 494.6 494.7 512.0 534.6 37308.0 39255.6 39682.8 41606.6 41779.0 40633.4 40885.6 41025.8 41027.8 41048.0 8243.0 9099.3 9261.3 9310.2 9327.5 8349.9 9924.5 10532.2 10890.0 11300.2 11169.9 11210.7 11213.0 11223.9 11268.6 11064.8 11135.7 11155.2 11191.1 11221.5 11063.7 11134.8 11148.2 11161.4 11164.3 38375.4 38505.0 38681.8 38834.0 38898.6 3749.2 3976.8 3992.6 3995.9 4000.4 29652.3 29830.8 30044.5 30136.5 30172.4 3003.8 3073.5 3203.3 3275.8 3285.8 328543.0 335941.3 346476.5 364132.4 366013.0 50711.5 57379.0 57431.4 57593.0 57611.7 48767.8 60226.7 60548.0 60551.7 60666.7 15408.5 15577.9 15589.8 15594.1 15595.8 10915.1 11147.7 12005.9 12040.6 12067.5 15077.8 15348.5 15385.5 15527.7 15667.7 6024.3 6079.0 6096.8 6099.2 6135.8 727.7 843.8 861.6 863.2 865.6 24343.5 25295.5 27484.0 27956.3 28540.9 989.2 994.8 1013.7 1024.3 1173.0 13649.2 13876.6 13907.9 14024.3 14034.5 618125.0 619957.0 622008.0 622327.0 629714.0 48742.0 48816.8 48879.0 48990.0 48999.8 1335.3 1369.5 1381.5 1394.2 1396.9 24722.5 25246.0 25934.6 26258.9 26393.0 32081.0 42800.8 42825.8 42879.4 42955.8 525122.0 526561.0 527073.0 527348.0 527801.0 23218.5 23429.9 23533.0 23934.9 24032.4 33368.8 35027.6 36135.5 40995.0 41238.0 33154.8 34742.0 35586.6 36061.6 36083.0 33724.0 34168.3 34414.5 35126.6 35225.1 33957.8 34098.1 34283.1 34359.3 34387.8 24652.4 29823.3 30704.8 30884.8 31423.1 21972.2 23656.5 23881.3 23958.5 23975.8 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:33144.23 Median:21017.06 stddev:89628.09 (426.5%) stddev-sans-first:89845.76 04:45:44 INFO - PROCESS | 1643 | Values: 378.3 384.2 388.6 397.8 411.2 17971.1 19657.0 20200.0 20414.5 20453.6 19362.7 19639.0 19825.6 20141.9 20188.3 4967.6 5432.3 5643.3 5895.1 5930.1 4584.9 4689.5 4974.9 5631.6 5709.1 5536.8 5691.8 5742.8 5799.2 5800.2 5720.2 5788.2 5788.7 5792.4 5801.4 4921.8 5003.1 5083.4 5085.3 5164.7 23759.8 24042.4 24207.4 24861.5 24880.9 2599.9 2775.6 2797.7 2809.5 2824.8 9888.9 15498.9 15502.9 15719.4 15755.4 1535.5 1787.5 1830.0 1833.8 1864.4 111631.0 113633.4 113786.0 113865.0 114993.0 11361.0 11397.9 11488.5 11495.8 11696.8 14211.4 14366.7 14416.1 14456.9 14459.8 8297.5 8472.4 8484.8 8495.0 8564.3 8266.4 8302.0 8430.2 8455.2 8538.4 6336.6 6391.4 7151.7 7168.6 7175.2 1856.4 1892.8 1896.5 1896.9 1900.4 910.9 982.0 983.6 998.3 1036.2 20884.3 20901.0 21014.6 21019.5 21198.6 1011.4 1023.5 1042.8 1048.2 1061.6 10387.3 11204.6 11230.5 11233.2 11245.0 468820.0 542548.0 544272.0 547866.0 551733.0 129558.4 139536.0 140307.0 140891.0 141481.3 965.8 1003.8 1014.6 1019.7 1026.4 13055.0 14246.7 16080.0 19595.4 20061.4 36482.0 43309.9 49857.8 54604.0 54851.8 75761.0 75950.5 76462.6 76683.6 77439.0 7308.6 7559.8 8291.5 8294.5 8696.9 11238.4 11647.2 12813.7 12848.0 13110.6 9655.0 10106.7 10351.4 11030.9 11150.4 10958.6 11081.0 11142.3 11257.4 11333.5 11229.5 11638.4 11642.9 11671.9 11672.4 7425.5 7581.2 7653.5 7708.7 7811.2 10686.1 10828.5 10941.0 11159.3 11533.9 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5302.02 Median:5325.47 stddev:2886.30 (54.2%) stddev-sans-first:2879.69 04:45:44 INFO - PROCESS | 1643 | Values: 1424.7 1429.1 1429.5 1436.3 1450.1 7442.3 7752.1 8207.1 8490.8 8526.9 7939.5 8236.5 8247.9 8357.4 8368.0 2268.3 2310.9 2327.3 2334.2 2340.1 2030.5 2105.7 2302.4 2327.2 2349.9 2218.3 2289.4 2301.5 2308.0 2309.4 2306.3 2314.4 2322.7 2334.2 2339.1 2272.4 2305.1 2331.6 2336.7 2356.9 8325.5 8383.0 8424.3 8435.3 8463.3 5066.6 5233.6 5237.9 5244.4 5264.7 3344.1 3534.3 3594.1 3602.7 3607.3 1175.4 1212.3 1231.4 1254.1 1260.4 9104.0 9157.3 10612.5 10749.2 10762.1 3522.8 3957.5 4143.3 4651.4 4714.5 9393.9 9475.2 9540.9 9551.7 9591.1 5242.3 5243.9 5256.7 5286.2 5293.8 4593.4 4692.5 4775.8 4848.6 4859.1 3756.9 3782.0 3850.9 3882.6 3905.3 972.3 1199.2 1225.7 1256.8 1270.0 835.7 839.1 868.3 898.0 898.8 4946.4 5300.4 5377.2 5389.6 5552.6 802.4 941.5 973.7 988.8 1025.1 2650.6 3565.7 3568.3 3656.2 3671.3 9580.8 9674.9 9841.6 9887.4 9950.6 7360.6 8703.9 9642.4 9813.8 9875.0 6794.6 7564.1 7656.0 7693.2 7785.8 4595.9 5350.5 5352.7 5382.6 5442.0 8846.0 9089.2 9099.6 9206.7 9328.7 9197.2 9202.9 9306.5 9360.2 9403.2 6972.9 7056.5 7091.0 7169.5 7188.0 6863.3 7760.0 7768.4 7768.4 7790.9 6998.7 7089.0 7104.0 7113.0 7225.7 6909.2 6916.2 6916.4 6931.1 6978.0 6021.1 6223.7 6278.6 6318.1 6326.5 3927.9 4542.2 4569.2 4613.7 4685.6 4092.4 4256.3 4669.8 4674.5 4718.2 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4519.06 Median:4997.76 stddev:1363.47 (27.3%) stddev-sans-first:1330.59 04:45:44 INFO - PROCESS | 1643 | Values: 332.0 356.1 362.5 364.8 370.3 4609.9 5102.9 5511.6 5515.9 5536.3 4530.7 5399.8 5414.0 5431.3 5450.0 3597.3 3976.7 4145.6 4196.5 4204.9 4225.7 4228.5 4239.6 4259.2 4262.4 3884.9 3893.0 3938.9 4225.5 4261.2 4066.6 4166.4 4234.3 4243.1 4247.4 3651.8 3708.1 3712.6 3715.3 3733.9 4313.7 4888.9 5009.7 5251.7 5278.0 4735.3 4862.4 4922.7 4930.9 4996.6 4528.5 5240.7 5528.3 5715.9 5745.9 3282.4 3876.6 3881.8 3903.5 3910.4 4484.8 5310.0 5441.9 5855.8 6017.4 3493.9 3504.2 3724.8 3769.1 3897.7 3696.5 3758.9 3961.8 3986.8 4007.7 4640.4 4647.3 4680.9 4682.8 4705.7 5478.0 5704.7 5746.9 5761.7 5822.7 1112.6 1135.8 1208.6 1287.8 1288.9 736.8 857.9 862.7 883.8 885.7 4538.7 4794.2 4806.4 4995.3 4999.0 4951.1 4991.0 5006.9 5018.0 5381.5 4101.7 5118.5 5135.7 5141.8 5261.8 2514.6 2668.4 2954.7 2968.4 2980.0 5122.4 5429.5 5614.2 5654.7 5844.3 5349.6 5475.9 5781.8 5837.5 5852.9 4978.8 5381.4 5534.6 5754.3 5910.4 4941.7 5015.4 5125.1 5392.0 5718.8 4914.9 5337.4 5436.3 5592.4 5607.7 5127.4 5440.3 5531.0 5689.3 5870.6 3894.7 3901.9 3931.7 3935.3 3966.2 5026.9 5839.0 5893.1 5926.5 5975.7 5446.5 5664.9 5678.4 5694.5 5702.2 4728.4 4973.8 5388.5 5437.8 5632.9 4782.3 5350.0 5375.2 5595.5 5620.0 4674.9 5655.3 5670.8 5674.7 5689.9 4581.5 5546.4 5584.6 5611.6 5634.5 04:45:44 INFO - PROCESS | 1643 | 04:45:44 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20413.33 Median:51451.61 stddev:29918.72 (58.1%) stddev-sans-first:29966.05 04:45:44 INFO - PROCESS | 1643 | Values: 705.5 762.4 775.5 799.3 812.3 15151.4 15659.5 15666.0 15701.2 15729.9 14357.6 14498.9 14528.9 14544.4 14556.9 678.9 707.1 830.2 856.5 867.3 848.9 869.5 873.0 874.0 875.7 924.9 927.3 928.0 928.4 930.3 912.0 918.4 919.3 922.1 922.6 685.8 689.1 690.5 691.4 692.1 14690.4 14898.9 14967.7 15052.8 15512.9 14131.9 14451.8 14468.1 14478.5 14502.1 11752.3 11837.5 11864.3 11948.8 12227.3 3687.9 3699.7 3718.2 3728.7 3853.9 107435.0 107688.5 108106.0 108769.0 108946.0 10878.6 11092.9 11193.9 11212.3 11229.8 22929.9 22939.4 22953.9 22990.2 23013.0 7597.5 7668.4 7778.7 7815.3 7990.6 4195.1 4220.7 4308.1 4321.5 4398.1 3432.0 3444.7 3521.4 3526.5 3529.0 553.9 619.4 644.3 648.7 661.0 551.7 657.3 673.3 673.4 680.2 8854.8 9244.3 9472.6 9477.6 9860.8 634.5 658.9 675.3 682.0 693.0 4460.9 4571.5 4583.9 4625.5 4634.7 85615.0 94985.0 104528.0 106386.0 107463.5 66569.0 92283.0 93507.0 94399.0 94900.0 80069.6 80802.0 81106.0 82388.4 83929.2 7478.3 8975.7 9000.5 9005.2 9182.0 51255.7 51647.5 51727.5 52935.0 54072.7 69085.7 71644.3 71994.6 72181.0 72253.6 10295.7 11758.9 11923.4 11939.4 12291.6 12646.6 12815.4 12907.2 12972.5 13234.9 11925.8 12084.8 12153.2 12176.5 12479.3 10594.7 11811.9 11896.0 11954.0 12098.3 11825.7 11846.5 12043.2 12099.3 12474.0 7927.7 9657.3 9992.2 10262.4 10748.5 7196.7 8793.6 8909.2 8945.4 9043.0 04:45:44 INFO - PROCESS | 1643 | -------- Summary: end -------- 04:45:44 INFO - PROCESS | 1643 | 04:45:45 INFO - TEST-INFO | 1643: exit 0 04:45:45 INFO - TEST-OK | dromaeo_css | took 1116709ms 04:45:45 INFO - TEST-START | kraken 04:45:45 INFO - operating with platform_type : mac_ 04:45:45 INFO - Initialising browser for kraken test... 04:45:45 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpaT3uWx/profile http://localhost:49248/getInfo.html) 04:45:45 INFO - PROCESS | 1655 | 1483533945787 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/FirefoxNightly.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/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4901 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3756 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2789 < callProvider()@resource://gre/modules/AddonManager.jsm:260 < _startProvider()@resource://gre/modules/AddonManager.jsm:755 < startup()@resource://gre/modules/AddonManager.jsm:937 < startup()@resource://gre/modules/AddonManager.jsm:2886 < observe()@resource://gre/components/addonManager.js:65 04:45:48 INFO - PROCESS | 1655 | 2017-01-04 04:45:48.084 plugin-container[1657:10014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 04:45:48 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:45:48 INFO - PROCESS | 1655 | 2017-01-04 04:45:48.084 plugin-container[1657:10014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 04:45:48 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:45:48 INFO - PROCESS | 1655 | 2017-01-04 04:45:48.085 plugin-container[1657:10014] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:45:48 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 04:45:48 INFO - PROCESS | 1655 | colorDepth:24 04:45:48 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 04:45:48 INFO - PROCESS | 1655 | __metrics 04:45:48 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 04:45:48 INFO - TEST-INFO | 1655: exit 0 04:45:48 INFO - Browser initialized. 04:45:48 INFO - Running cycle 1/1 for kraken test... 04:45:48 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpaT3uWx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:51 INFO - PROCESS | 1658 | 2017-01-04 04:45:51.280 plugin-container[1659:10143] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 04:45:51 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:45:51 INFO - PROCESS | 1658 | 2017-01-04 04:45:51.281 plugin-container[1659:10143] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 04:45:51 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:45:51 INFO - PROCESS | 1658 | 2017-01-04 04:45:51.281 plugin-container[1659:10143] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:46:01 INFO - PROCESS | 1658 | RSS: Main: 231931904 04:46:01 INFO - PROCESS | 1658 | 04:46:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 04:46:05 INFO - PROCESS | 1658 | RSS: Main: 244748288 04:46:05 INFO - PROCESS | 1658 | 04:46:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 04:46:08 INFO - PROCESS | 1658 | RSS: Main: 252825600 04:46:08 INFO - PROCESS | 1658 | 04:46:12 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 04:46:12 INFO - PROCESS | 1658 | RSS: Main: 255643648 04:46:12 INFO - PROCESS | 1658 | 04:46:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 04:46:15 INFO - PROCESS | 1658 | RSS: Main: 257683456 04:46:15 INFO - PROCESS | 1658 | 04:46:17 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:46:18 INFO - PROCESS | 1658 | RSS: Main: 258330624 04:46:18 INFO - PROCESS | 1658 | 04:46:20 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 04:46:21 INFO - PROCESS | 1658 | RSS: Main: 263860224 04:46:21 INFO - PROCESS | 1658 | 04:46:24 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 04:46:25 INFO - PROCESS | 1658 | RSS: Main: 263184384 04:46:25 INFO - PROCESS | 1658 | 04:46:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 04:46:28 INFO - PROCESS | 1658 | RSS: Main: 263081984 04:46:28 INFO - PROCESS | 1658 | 04:46:30 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:46:31 INFO - PROCESS | 1658 | RSS: Main: 262496256 04:46:31 INFO - PROCESS | 1658 | 04:46:33 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:46:33 INFO - PROCESS | 1658 | RSS: Main: 262246400 04:46:33 INFO - PROCESS | 1658 | 04:46:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:46:36 INFO - PROCESS | 1658 | RSS: Main: 262344704 04:46:36 INFO - PROCESS | 1658 | 04:46:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:46:40 INFO - PROCESS | 1658 | RSS: Main: 262574080 04:46:40 INFO - PROCESS | 1658 | 04:46:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:46:43 INFO - PROCESS | 1658 | RSS: Main: 262455296 04:46:43 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 04:46:45 INFO - PROCESS | 1658 | RSS: Main: 264257536 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | __start_tp_report 04:46:45 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 04:46:45 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 04:46:45 INFO - PROCESS | 1658 | |i|pagename|runs| 04:46:45 INFO - PROCESS | 1658 | |0;ai-astar;106;118;117;119;130;174;104;164;174;174 04:46:45 INFO - PROCESS | 1658 | |1;audio-beat-detection;144;114;112;143;151;152;147;114;130;134 04:46:45 INFO - PROCESS | 1658 | |2;audio-dft;198;230;268;197;275;204;189;268;275;193 04:46:45 INFO - PROCESS | 1658 | |3;audio-fft;83;58;73;110;52;71;86;86;96;59 04:46:45 INFO - PROCESS | 1658 | |4;audio-oscillator;85;73;68;60;57;66;119;105;59;100 04:46:45 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;91;90;98;86;104;90;90;89;88;74 04:46:45 INFO - PROCESS | 1658 | |6;imaging-darkroom;97;104;139;96;95;96;97;97;96;96 04:46:45 INFO - PROCESS | 1658 | |7;imaging-desaturate;144;149;120;95;94;91;91;93;95;82 04:46:45 INFO - PROCESS | 1658 | |8;json-parse-financial;60;60;61;62;62;62;60;64;62;62 04:46:45 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;69;74;67;79;79;77;67;77;101;87 04:46:45 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;91;85;88;89;89;90;88;89;89;92 04:46:45 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;160;160;160;140;144;152;154;146;187;150 04:46:45 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;175;174;173;178;178;172;177;176;172;176 04:46:45 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;68;82;94;72;67;66;67;70;66;69 04:46:45 INFO - PROCESS | 1658 | __end_tp_report 04:46:45 INFO - PROCESS | 1658 | __start_cc_report 04:46:45 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,914 04:46:45 INFO - PROCESS | 1658 | __end_cc_report 04:46:45 INFO - PROCESS | 1658 | __startTimestamp1483534005809__endTimestamp 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | ------- Summary: start ------- 04:46:45 INFO - PROCESS | 1658 | Number of tests: 14 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:138.00 Median:147.00 stddev:29.83 (20.3%) stddev-sans-first:29.31 04:46:45 INFO - PROCESS | 1658 | Values: 106.0 118.0 117.0 119.0 130.0 174.0 104.0 164.0 174.0 174.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:143.50 stddev:15.86 (11.0%) stddev-sans-first:16.41 04:46:45 INFO - PROCESS | 1658 | Values: 144.0 114.0 112.0 143.0 151.0 152.0 147.0 114.0 130.0 134.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:229.70 Median:249.00 stddev:37.68 (15.1%) stddev-sans-first:38.18 04:46:45 INFO - PROCESS | 1658 | Values: 198.0 230.0 268.0 197.0 275.0 204.0 189.0 268.0 275.0 193.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:77.40 Median:78.00 stddev:18.28 (23.4%) stddev-sans-first:19.28 04:46:45 INFO - PROCESS | 1658 | Values: 83.0 58.0 73.0 110.0 52.0 71.0 86.0 86.0 96.0 59.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:63.00 stddev:21.92 (34.8%) stddev-sans-first:23.15 04:46:45 INFO - PROCESS | 1658 | Values: 85.0 73.0 68.0 60.0 57.0 66.0 119.0 105.0 59.0 100.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:90.00 stddev:7.73 (8.6%) stddev-sans-first:8.19 04:46:45 INFO - PROCESS | 1658 | Values: 91.0 90.0 98.0 86.0 104.0 90.0 90.0 89.0 88.0 74.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:96.00 stddev:13.48 (14.0%) stddev-sans-first:14.21 04:46:45 INFO - PROCESS | 1658 | Values: 97.0 104.0 139.0 96.0 95.0 96.0 97.0 97.0 96.0 96.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:105.40 Median:92.00 stddev:23.73 (25.8%) stddev-sans-first:20.65 04:46:45 INFO - PROCESS | 1658 | Values: 144.0 149.0 120.0 95.0 94.0 91.0 91.0 93.0 95.0 82.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:62.00 stddev:1.27 (2.0%) stddev-sans-first:1.22 04:46:45 INFO - PROCESS | 1658 | Values: 60.0 60.0 61.0 62.0 62.0 62.0 60.0 64.0 62.0 62.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:77.70 Median:77.00 stddev:10.29 (13.4%) stddev-sans-first:10.42 04:46:45 INFO - PROCESS | 1658 | Values: 69.0 74.0 67.0 79.0 79.0 77.0 67.0 77.0 101.0 87.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:89.00 stddev:1.89 (2.1%) stddev-sans-first:1.86 04:46:45 INFO - PROCESS | 1658 | Values: 91.0 85.0 88.0 89.0 89.0 90.0 88.0 89.0 89.0 92.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:157.00 stddev:13.17 (8.4%) stddev-sans-first:13.85 04:46:45 INFO - PROCESS | 1658 | Values: 160.0 160.0 160.0 140.0 144.0 152.0 154.0 146.0 187.0 150.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:176.00 stddev:2.28 (1.3%) stddev-sans-first:2.42 04:46:45 INFO - PROCESS | 1658 | Values: 175.0 174.0 173.0 178.0 178.0 172.0 177.0 176.0 172.0 176.0 04:46:45 INFO - PROCESS | 1658 | 04:46:45 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:69.50 stddev:9.04 (13.0%) stddev-sans-first:9.46 04:46:45 INFO - PROCESS | 1658 | Values: 68.0 82.0 94.0 72.0 67.0 66.0 67.0 70.0 66.0 69.0 04:46:45 INFO - PROCESS | 1658 | -------- Summary: end -------- 04:46:45 INFO - PROCESS | 1658 | 04:46:46 INFO - TEST-INFO | 1658: exit 0 04:46:46 INFO - TEST-OK | kraken | took 61453ms 04:46:46 INFO - SUITE-END | took 1178s 04:46:46 INFO - Completed test suite (00:19:38) 04:46:46 INFO - javascript benchmark 04:46:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7099.772036812607, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2712.891484340626, 3322.601287845459, 3323.6842499962495, 3333.1833700743323, 3347.079553122891, 5173.844784656461, 5484.314460692413, 5584.664920104797, 5651.547876169907, 5676.233708449358, 4761.785744635114, 5413.593980451472, 5514.5864060195445, 5544.417836127199, 5553.0, 5008.499150084999, 5025.070361983045, 5071.442141364447, 5121.590272778169, 5126.794928202868, 4436.489803672571, 5083.110455670243, 5131.897362052757, 5133.614978876581, 5240.196469930515, 4974.577160941288, 5136.289716976926, 5158.252053452247, 5182.067227898977, 5189.688618682872, 5022.221555658879, 5089.007643509517, 5094.184930411154, 5098.694078355274, 5099.541041306263, 4970.676906001098, 5000.349954505891, 5041.0422019816115, 5092.312537807423, 5101.846944591668, 4036.0, 5477.507024367826, 5535.501804837584, 5583.302087239095, 5627.437256274381, 5888.116782482619, 6060.909086363708, 6068.878622427571, 6073.605215661012, 6076.5442591805795, 6005.4385859676795, 6106.2977757558, 6108.938910610926, 6122.785702500391, 6128.846778830521, 5561.304836895388, 5638.4361563843695, 5641.887162256732, 5644.774209031634, 5654.180143879114, 6112.480439162632, 6138.878900475427, 6139.737467782837, 6145.525073982212, 6158.784442544489, 5936.406359364029, 5938.554608404387, 5940.198073260054, 5945.256842894638, 5949.3753155918, 6041.818745437644, 6056.424639659225, 6081.324217344354, 6091.238595175603, 6107.236595425572, 5913.615614985012, 5941.792037278675, 6008.579399441997, 6069.848253793691, 6077.726502307407, 6018.638881667026, 6021.819345419644, 6022.277326720821, 6052.878942421127, 6054.152418661391, 4819.228923372244, 4823.27650852375, 4830.203016502308, 4850.684705494132, 4867.975660121677, 4237.0, 4249.617534421855, 4282.015136518618, 4343.522212556616, 4345.630621397154, 5241.764120614582, 5577.609567330248, 5579.721013949367, 5664.037113690682, 5676.120201368794, 5201.0, 6100.603460775035, 6166.845828854225, 6207.6275423474735, 6227.595206311576, 5712.914306285326, 5715.714214289269, 5716.1997320375185, 5729.083346664514, 5747.3677895431465, 5163.122269214243, 5172.637915345965, 5230.293910322134, 5276.0, 5293.338332708411, 6163.445289923929, 6178.196834411498, 6194.845128871724, 6210.596311239755, 6260.154879091447, 6123.663198524124, 6173.47525460323, 6194.0708893666315, 6228.252609686867, 6238.75044998195, 5950.0, 6220.720067596879, 6229.875402492015, 6244.937550624617, 6248.094026366242, 4215.957840421556, 5923.437273459014, 5945.0, 6008.068749343859, 6036.63780173191, 5836.941630583639, 6019.0, 6043.607165534227, 6075.574709770273, 6128.540359473147, 5079.974600126976, 5671.602987790815, 5869.618474799125, 5893.233879595625, 5944.0, 5773.6824474654295, 5902.704864756831, 5911.083782013795, 5911.7635294587735, 5922.644641321534, 5687.60186786921, 5694.402087780723, 5709.34342550612, 5763.798267060801, 5785.739641716049, 5826.621269617462, 5839.445252700986, 5869.442402971711, 5877.265341832271, 5887.970560147171, 5716.657000579979, 5751.223584815996, 5754.395788442153, 5769.480746732815, 5784.1034639630925], "value": 5514.784571059517, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.95706407891237, 494.6339708615623, 494.6735154797833, 512.0169274992015, 534.6043927493654, 37308.0, 39255.60744392555, 39682.80158599206, 41606.58393416065, 41779.0, 40633.39049914254, 40885.59114408855, 41025.794871025675, 41027.79486102573, 41048.0, 8243.010838699365, 9099.272058235341, 9261.305402094837, 9310.208632266265, 9327.533623318841, 8349.874751878726, 9924.45415502147, 10532.210084243674, 10890.0, 11300.208985371028, 11169.888301116966, 11210.719732006684, 11212.990830825265, 11223.887761122365, 11268.605598804044, 11064.834027489595, 11135.665930022113, 11155.219134660578, 11191.104711623051, 11221.495032723466, 11063.72340691481, 11134.832977505344, 11148.21962462628, 11161.38612376319, 11164.330140191614, 38375.42436863449, 38505.0, 38681.80659096715, 38834.02914927121, 38898.61101388978, 3749.212665340282, 3976.76139431635, 3992.5807790181902, 3995.9400608990886, 4000.3599424092295, 29652.258693532618, 29830.806767729264, 30044.54933176004, 30136.54795178074, 30172.396552069054, 3003.8498075096377, 3073.4928736758407, 3203.2952750394875, 3275.836208189605, 3285.8192799396024, 328543.0, 335941.32029339694, 346476.5352346495, 364132.3586764125, 366013.0, 50711.49288507141, 57379.0, 57431.425685743445, 57593.0, 57611.71194144002, 48767.75616121897, 60226.69886650539, 60548.0, 60551.69724151351, 60666.696666516385, 15408.460703875311, 15577.922110389602, 15589.76615350748, 15594.064356138668, 15595.766063509056, 10915.072218861327, 11147.72130696739, 12005.939970300267, 12040.638780836589, 12067.517299308105, 15077.773833392508, 15348.539543813704, 15385.461508847136, 15527.689446211012, 15667.686646267011, 6024.27708674953, 6079.0, 6096.786612468542, 6099.176611157437, 6135.754569817157, 727.7198278662647, 843.8270154618411, 861.5778268648443, 863.1541089731973, 865.5931712095305, 24343.513129737305, 25295.494090118096, 27484.0, 27956.301092472444, 28540.857295713387, 989.1749721763335, 994.8010397920444, 1013.7212266626736, 1024.2778840917135, 1172.9824052639217, 13649.181049137083, 13876.58370248894, 13907.930460347634, 14024.298785060706, 14034.508792192224, 618125.0, 619957.0, 622008.0, 622327.0, 629714.0, 48742.0, 48816.77958051006, 48879.0, 48990.0, 48999.75500122548, 1335.2789493673308, 1369.5206677662939, 1381.4819442708783, 1394.17743531317, 1396.9371378288001, 24722.516649001118, 25245.990160394114, 25934.610980834925, 26258.86870565635, 26393.0, 32081.0, 42800.78599606982, 42825.785871070446, 42879.3568096485, 42955.78522107495, 525122.0, 526561.0, 527073.0, 527348.0, 527801.0, 23218.53562928698, 23429.882850585636, 23533.0, 23934.88032559826, 24032.39919002039, 33368.83315583504, 35027.649723503455, 36135.45796812997, 40995.0, 41238.0, 33154.834225828716, 34742.0, 35586.64413355937, 36061.639383606875, 36083.0, 33724.0, 34168.31663366769, 34414.48378274278, 35126.64873351234, 35225.11937201488, 33957.8114765982, 34098.14754631154, 34283.14292142716, 34359.31281374308, 34387.82806085954, 24652.38369040788, 29823.254418639706, 30704.846475768372, 30884.84557577198, 31423.05730828079, 21972.230971915906, 23656.526869462858, 23881.283561493183, 23958.52082958296, 23975.76024239735], "value": 18646.753462969926, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.29070492825934, 384.1989451992596, 388.615270881827, 397.7613431940838, 411.1920077048599, 17971.10144492775, 19657.01714914252, 20200.0, 20414.489637759045, 20453.590928181427, 19362.70955935662, 19639.0, 19825.603487930268, 20141.892195929217, 20188.29340973066, 4967.552920237178, 5432.348118225818, 5643.322801263854, 5895.145203945425, 5930.080837470199, 4584.876705207213, 4689.507601701824, 4974.88065185334, 5631.577631677641, 5709.143628455744, 5536.833894983157, 5691.800786972457, 5742.770289188448, 5799.217105690741, 5800.187973683687, 5720.227769251161, 5788.247527821399, 5788.681622510781, 5792.362840087587, 5801.419858014207, 4921.827736029221, 5003.124453220708, 5083.440821509631, 5085.338905942241, 5164.6642968207325, 23759.762402375927, 24042.39894002664, 24207.394815129588, 24861.502769944498, 24880.87559562209, 2599.89798495526, 2775.5559110542417, 2797.692253852075, 2809.4802461544677, 2824.802263841532, 9888.912219655833, 15498.922505387514, 15502.922485387613, 15719.449819256386, 15755.369785208577, 1535.5239875638476, 1787.517370310022, 1829.9940487998465, 1833.825786550289, 1864.4127099963468, 111631.0, 113633.4318328403, 113786.0, 113864.99999999917, 114993.0, 11361.0, 11397.943010284895, 11488.54045838157, 11495.827562586568, 11696.824547631792, 14211.365692945443, 14366.712665746627, 14416.062955907832, 14456.927715361357, 14459.78310325346, 8297.502149870907, 8472.406931514735, 8484.830303393897, 8495.0, 8564.3148548116, 8266.421350505407, 8301.958490207511, 8430.199131082538, 8455.239028487342, 8538.359623028298, 6336.556441049082, 6391.360863913665, 7151.678174482161, 7168.569885806868, 7175.210726820045, 1856.378113332022, 1892.810718928096, 1896.535348839543, 1896.8577356698304, 1900.353879680915, 910.8841668955608, 981.9803603928025, 983.6311383231289, 998.2662742883974, 1036.1555332404066, 20884.26905058346, 20901.02880452709, 21014.640857813705, 21019.474513137295, 21198.576028479343, 1011.3780025284398, 1023.5087158164123, 1042.7653777900064, 1048.2452634103433, 1061.6125114333367, 10387.324823886574, 11204.60783872577, 11230.494627741771, 11233.213675042833, 11244.987951084442, 468820.0, 542548.0, 544272.0, 547866.0, 551733.0, 129558.35220823836, 139536.0, 140307.0, 140891.0, 141481.29259353637, 965.7633879699378, 1003.844404117363, 1014.60937539048, 1019.6788011776267, 1026.3687831983277, 13055.0, 14246.717795398465, 16080.035197888164, 19595.431265911793, 20061.398158055283, 36482.0, 43309.91725206895, 49857.753556161384, 54604.0, 54851.80592776244, 75761.0, 75950.480990381, 76462.61768691121, 76683.61658191674, 77439.0, 7308.634568271459, 7559.773206803805, 8291.543965081737, 8294.502329860228, 8696.869546956674, 11238.438078095898, 11647.242929209575, 12813.679658008625, 12848.036397270429, 13110.606681799562, 9654.951725241328, 10106.696799095746, 10351.378917264989, 11030.944845275722, 11150.386728729673, 10958.616448424267, 11081.0, 11142.27575207609, 11257.437128143396, 11333.489992950172, 11229.49467273991, 11638.41807909618, 11642.88357116418, 11671.941640292083, 11672.358020308624, 7425.517341372793, 7581.241875812375, 7653.464257501922, 7708.691652333844, 7811.218878112143, 10686.0916822071, 10828.5127169276, 10941.0, 11159.330440173615, 11533.88466115328], "value": 9633.740058198897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.7150569886026, 1429.0782445322768, 1429.5425463851566, 1436.2603259321445, 1450.144414795271, 7442.330190282873, 7752.147263800977, 8207.138250483702, 8490.830183396336, 8526.872096918543, 7939.523628582289, 8236.517426863162, 8247.927769389986, 8357.373197010218, 8368.0376756673, 2268.319504148753, 2310.9422264443438, 2327.290176496168, 2334.2180369576213, 2340.1341503643675, 2030.5431277962414, 2105.673620588811, 2302.4243939015246, 2327.1505900346338, 2349.8590084594894, 2218.267971569378, 2289.3933107726466, 2301.4821665125382, 2308.0190918859503, 2309.4226443389152, 2306.2965795432387, 2314.375118717945, 2322.674825524428, 2334.2413715542516, 2339.099446713019, 2272.397814579138, 2305.13557415969, 2331.603627383349, 2336.6728657987896, 2356.8939397727145, 8325.542095184763, 8383.0, 8424.326053915733, 8435.325173986128, 8463.322934165251, 5066.594672426234, 5233.581313494911, 5237.869053273661, 5244.370675518962, 5264.710440925747, 3344.147242453159, 3534.3108093921696, 3594.137407022322, 3602.7117830573493, 3607.2965771674535, 1175.4416652090274, 1212.345333519907, 1231.359692959665, 1254.0845183016359, 1260.3887114749339, 9104.0, 9157.267418606496, 10612.46937653122, 10749.193810464169, 10762.139028877671, 3522.806245656462, 3957.5448823385104, 4143.3370660694145, 4651.418572678415, 4714.481407045222, 9393.90606093944, 9475.24198064167, 9540.856887146698, 9551.713448596553, 9591.088846559565, 5242.266082748418, 5243.895122097537, 5256.737163141827, 5286.233496143038, 5293.841184764463, 4593.356930029799, 4692.507286734844, 4775.832845850378, 4848.5636292733825, 4859.125357435695, 3756.924861502809, 3781.962180378216, 3850.942235866464, 3882.6216693074116, 3905.33609286422, 972.3485264872552, 1199.166579227446, 1225.6629426907493, 1256.824044633752, 1269.9555515557097, 835.6824406725406, 839.0686338164633, 868.2836659755727, 897.9865302020474, 898.7528429681889, 4946.441870810683, 5300.443453437333, 5377.220303056035, 5389.649672771257, 5552.555795536428, 802.3781569283851, 941.538646063424, 973.73709098543, 988.8318985772436, 1025.0620682075967, 2650.5759078547726, 3565.679088881961, 3568.286342731464, 3656.232191239816, 3671.3391589514144, 9580.808383832284, 9674.854877176847, 9841.60633574649, 9887.357321774061, 9950.552225149886, 7360.558366498028, 8703.912010998625, 9642.373245739005, 9813.754656133653, 9875.012498750068, 6794.558353706993, 7564.130125035581, 7655.999999999889, 7693.192214817475, 7785.80535486606, 4595.885102872455, 5350.491703288181, 5352.652077614943, 5382.596305277199, 5442.0, 8845.955770221108, 9089.181973622406, 9099.59051842682, 9206.723798286062, 9328.673496427593, 9197.17225449686, 9202.861957070783, 9306.488143152192, 9360.15758581731, 9403.200727938201, 6972.860542789217, 7056.541324814076, 7091.042709234393, 7169.4622903281415, 7187.9640601796655, 6863.284178955261, 7760.0, 7768.378529717722, 7768.417368697484, 7790.88313675284, 6998.720051197895, 7088.964555177191, 7104.0, 7113.03973963529, 7225.747098851515, 6909.205441374105, 6916.20463646687, 6916.377526022682, 6931.133608298963, 6978.0, 6021.096835474538, 6223.657698826608, 6278.591891527036, 6318.08387783778, 6326.493880489457, 3927.941080883732, 4542.227821270326, 4569.2232320506255, 4613.677042606985, 4685.6017238535105, 4092.447519584818, 4256.254496043462, 4669.789859456265, 4674.5091765365505, 4718.245080787057], "value": 4322.406721763333, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.0006779593423, 356.0653285126542, 362.50699049292956, 364.81029864470474, 370.2871971454949, 4609.907801843965, 5102.902875881685, 5511.641743286684, 5515.917261241075, 5536.252605898202, 4530.7281563106235, 5399.83800485986, 5413.972930135354, 5431.293931788872, 5449.975263728629, 3597.2985267872778, 3976.7216294859372, 4145.626893579592, 4196.517400498951, 4204.915901681965, 4225.704200705952, 4228.5137209220875, 4239.554846741079, 4259.212045771542, 4262.424572682679, 3884.8834534964, 3892.9805350973206, 3938.901527461822, 4225.535191128974, 4261.190373828963, 4066.593340665939, 4166.416701661739, 4234.301340278866, 4243.111815242219, 4247.362895565674, 3651.8174091295327, 3708.147126160998, 3712.5706602957785, 3715.2755212733528, 3733.869314574004, 4313.741175529478, 4888.853334399938, 5009.724465154415, 5251.658642188246, 5278.0, 4735.266033764772, 4862.3922009748785, 4922.680025798348, 4930.876728081791, 4996.575291100261, 4528.547145285478, 5240.737963101829, 5528.308961379827, 5715.856828634248, 5745.942540574582, 3282.3763484937786, 3876.5735769065664, 3881.7679834095225, 3903.4925459690057, 3910.354791459406, 4484.8206071757095, 5309.973450132725, 5441.918371224435, 5855.795047173328, 6017.398260173947, 3493.877714279988, 3504.1940353718787, 3724.7522080102926, 3769.1142581493327, 3897.7466464679705, 3696.464012718141, 3758.9060273493383, 3961.7821019844187, 3986.80065996699, 4007.6793856491413, 4640.373549570832, 4647.257278520555, 4680.9297860532115, 4682.836100736458, 4705.717656940595, 5478.041342765, 5704.686242256633, 5746.856328591819, 5761.740721667535, 5822.73797679097, 1112.6105862947904, 1135.7614900870744, 1208.6313674329238, 1287.7682017236987, 1288.891553264192, 736.7642354446633, 857.8884744983112, 862.6808081010066, 883.7583195610177, 885.6545947080643, 4538.659600529973, 4794.232922732346, 4806.351142595775, 4995.325631039836, 4998.9500104999215, 4951.133551628523, 4991.028543725208, 5006.923511445057, 5018.035751756145, 5381.515663590218, 4101.671866250693, 5118.488151184852, 5135.743212839343, 5141.794328226903, 5261.842144735664, 2514.559952008428, 2668.3996100877534, 2954.719301666338, 2968.376640905391, 2979.9702002979693, 5122.436531981479, 5429.534025813008, 5614.24207731951, 5654.717264136777, 5844.327902291206, 5349.576271186444, 5475.890482190334, 5781.82654520365, 5837.474627283565, 5852.941470585325, 4978.800847966041, 5381.354237491526, 5534.584906132137, 5754.338251101177, 5910.379410161957, 4941.703497790144, 5015.448300686849, 5125.051865404912, 5391.973040134774, 5718.828435146952, 4914.852554423373, 5337.359516858002, 5436.266104075899, 5592.440755924375, 5607.747651355618, 5127.436131979782, 5440.29276194108, 5530.972345138249, 5689.260396148474, 5870.589058766016, 3894.6884249259424, 3901.9414708778822, 3931.6803086179293, 3935.311320518955, 3966.2067586483395, 5026.899462010666, 5838.970805145947, 5893.145493903446, 5926.525877929669, 5975.7012149391485, 5446.537044351275, 5664.915026274692, 5678.403767604507, 5694.515966142924, 5702.173184888251, 4728.432588089451, 4973.751312434291, 5388.488093631099, 5437.8368648940595, 5632.901584191043, 4782.282657601388, 5349.97325013388, 5375.19372094189, 5595.468430499096, 5619.971900140473, 4674.883127921829, 5655.321361436654, 5670.8015219467125, 5674.687892165971, 5689.886202276014, 4581.541845815392, 5546.4453554644215, 5584.581156413204, 5611.551075913999, 5634.464725851038], "value": 4089.1997072036547, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.5272967112035, 762.3748526208508, 775.5424299663198, 799.2886331165262, 812.2770734046695, 15151.39394424223, 15659.530214093567, 15666.0, 15701.214939253034, 15729.921350393246, 14357.641058973531, 14498.927505362484, 14528.927355363208, 14544.418223271057, 14556.854431455682, 678.8947713104477, 707.1266985273184, 830.1739768929923, 856.5203486047802, 867.3364875869964, 848.855694531928, 869.5478351257374, 872.9786150204244, 873.9905409252324, 875.6672464463495, 924.8797656304705, 927.3137877970321, 928.0209379105041, 928.3640706116332, 930.2511478259999, 912.0286894457381, 918.42598376015, 919.2599957034585, 922.0594993107027, 922.6217250927087, 685.807973767341, 689.1351354050685, 690.4959379652831, 691.401937324216, 692.0726226856037, 14690.412383504647, 14898.851011489854, 14967.700645987019, 15052.774208386883, 15512.922435387864, 14131.858681413158, 14451.78322325166, 14468.131912085308, 14478.493252736103, 14502.129872207595, 11752.294862308288, 11837.467313970807, 11864.288142711379, 11948.761024779456, 12227.348679452381, 3687.8893633191046, 3699.6670299672887, 3718.215256676783, 3728.6644202021675, 3853.8843834685003, 107435.0, 107688.46155769171, 108106.0, 108769.0, 108946.0, 10878.619248326271, 11092.944535277273, 11193.888061119285, 11212.327260364404, 11229.831552526719, 22929.853507324566, 22939.426514337276, 22953.885230573742, 22990.195343162908, 23012.964416601295, 7597.468177227541, 7668.424868134912, 7778.727744529027, 7815.25755053269, 7990.64042118106, 4195.119025004722, 4220.725652832556, 4308.052228509723, 4321.481422229353, 4398.120375924827, 3431.9828400857837, 3444.689977902001, 3521.3837578423672, 3526.4886591444097, 3528.9942366425958, 553.92522009529, 619.3558698953038, 644.3073695777058, 648.6691787188568, 660.96364699941, 551.7131091832225, 657.2572992518424, 673.2661399075032, 673.3569441183657, 680.1532092544813, 8854.77863053416, 9244.306676999251, 9472.591753939061, 9477.573509191965, 9860.753481162885, 634.520936692794, 658.8781075501048, 675.2774531251612, 681.9590824550543, 693.0, 4460.933086003648, 4571.542845715402, 4583.931241031387, 4625.514320996247, 4634.676941610993, 85615.0, 94985.0, 104527.95472045182, 106386.0, 107463.46268268609, 66569.0, 92283.0, 93507.0, 94399.0, 94899.99999999862, 80069.59965200137, 80802.0, 81106.0, 82388.352232955, 83929.16070839214, 7478.2521747824785, 8975.685850995184, 9000.54997250148, 9005.189532942066, 9181.989981102208, 51255.74372128116, 51647.483525164265, 51727.48272517227, 52935.0, 54072.72963635314, 69085.65457172682, 71644.28355716376, 71994.64002679953, 72181.0, 72253.63873180811, 10295.7426064346, 11758.882411175779, 11923.376857091425, 11939.388182595585, 12291.631251062483, 12646.557370491988, 12815.44876014949, 12907.161034532724, 12972.481100755864, 13234.933825330812, 11925.761484770082, 12084.758304833678, 12153.197219636782, 12176.512939482322, 12479.251244925334, 10594.682159535227, 11811.881881181422, 11896.0, 11954.0, 12098.334591597546, 11825.669285846023, 11846.466908988943, 12043.156979011384, 12099.33453660057, 12474.0, 7927.682892684228, 9657.275704322063, 9992.15066719337, 10262.384256944608, 10748.462576871281, 7196.676149573386, 8793.560321983747, 8909.242714369351, 8945.37382383227, 9043.0], "value": 7313.573333945665, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 117.0, 119.0, 130.0, 174.0, 104.0, 164.0, 174.0, 174.0], "value": 138.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 112.0, 143.0, 151.0, 152.0, 147.0, 114.0, 130.0, 134.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 230.0, 268.0, 197.0, 275.0, 204.0, 189.0, 268.0, 275.0, 193.0], "value": 229.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 73.0, 110.0, 52.0, 71.0, 86.0, 86.0, 96.0, 59.0], "value": 77.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 73.0, 68.0, 60.0, 57.0, 66.0, 119.0, 105.0, 59.0, 100.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 98.0, 86.0, 104.0, 90.0, 90.0, 89.0, 88.0, 74.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 104.0, 139.0, 96.0, 95.0, 96.0, 97.0, 97.0, 96.0, 96.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 149.0, 120.0, 95.0, 94.0, 91.0, 91.0, 93.0, 95.0, 82.0], "value": 105.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 62.0, 62.0, 62.0, 60.0, 64.0, 62.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 67.0, 79.0, 79.0, 77.0, 67.0, 77.0, 101.0, 87.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 88.0, 89.0, 89.0, 90.0, 88.0, 89.0, 89.0, 92.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 140.0, 144.0, 152.0, 154.0, 146.0, 187.0, 150.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 173.0, 178.0, 178.0, 172.0, 177.0, 176.0, 172.0, 176.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 82.0, 94.0, 72.0, 67.0, 66.0, 67.0, 70.0, 66.0, 69.0], "value": 72.1, "unit": "score"}]}]} 04:46:47 INFO - Return code: 0 04:46:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:46:47 ERROR - # TBPL SUCCESS # 04:46:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:47 INFO - 2017-01-04 04:46:47.105 screenresolution[1663:10527] starting screenresolution argv=screenresolution get 04:46:47 INFO - 2017-01-04 04:46:47.107 screenresolution[1663:10527] Display 0: 1600x1200x32@60 04:46:47 INFO - 2017-01-04 04:46:47.114 screenresolution[1664:10530] starting screenresolution argv=screenresolution list 04:46:47 INFO - Available Modes on Display 0 04:46:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:47 INFO - Intel Iris: 04:46:47 INFO - Chipset Model: Intel Iris 04:46:47 INFO - Type: GPU 04:46:47 INFO - Bus: Built-In 04:46:47 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:47 INFO - Vendor: Intel (0x8086) 04:46:47 INFO - Device ID: 0x0a2e 04:46:47 INFO - Revision ID: 0x0009 04:46:47 INFO - Displays: 04:46:47 INFO - Display: 04:46:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:47 INFO - Display Serial Number: 1600x1200 60 04:46:47 INFO - Main Display: Yes 04:46:47 INFO - Mirror: Off 04:46:47 INFO - Online: Yes 04:46:47 INFO - Rotation: Supported 04:46:47 INFO - Return code: 0 04:46:47 INFO - Running post-action listener: _resource_record_post_action 04:46:47 INFO - [mozharness: 2017-01-04 12:46:47.344430Z] Finished run-tests step (success) 04:46:47 INFO - Running post-run listener: _resource_record_post_run 04:46:47 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 38873088; Write bytes: 552072192; Read time: 250; Write time: 1683 04:46:47 INFO - TinderboxPrint: CPU usage
54.8% 04:46:47 INFO - TinderboxPrint: I/O read bytes / time
38,873,088 / 250 04:46:47 INFO - TinderboxPrint: I/O write bytes / time
552,072,192 / 1,683 04:46:47 INFO - TinderboxPrint: CPU idle
2,172.1 (45.2%) 04:46:47 INFO - TinderboxPrint: CPU system
970.8 (20.2%) 04:46:47 INFO - TinderboxPrint: CPU user
1,662.7 (34.6%) 04:46:47 INFO - TinderboxPrint: Swap in / out
288,272,384 / 0 04:46:47 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250601984; Write bytes: 250318848; Read time: 18231; Write time: 545 04:46:47 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 35600384; Write bytes: 300372992; Read time: 226; Write time: 1134 04:46:47 INFO - Running post-run listener: _upload_blobber_files 04:46:47 INFO - Blob upload gear active. 04:46:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:46:47 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 04:46:47 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'] 04:46:47 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 04:46:48 INFO - (blobuploader) - INFO - Open directory for files ... 04:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:46:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:48 INFO - (blobuploader) - INFO - Done attempting. 04:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:49 INFO - (blobuploader) - INFO - Done attempting. 04:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:51 INFO - (blobuploader) - INFO - Done attempting. 04:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:53 INFO - (blobuploader) - INFO - Done attempting. 04:46:53 INFO - (blobuploader) - INFO - Iteration through files over. 04:46:53 INFO - Return code: 0 04:46:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:46:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:46:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a65191e5a896a6cefa06e4336c19b2107ba57ee889c1cc1e940b36924af1907dc38589a794a19df17212364a31dc85c344db569bc8fef0a2e907a20c23cf1a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c6e48b220ea1978376cc68f92f4ab7793bc7164a258dfd7cfc0ca4e19df43a603c24076f2f15dd80e9f936755cbb1e1a157ca1f45ec33d6c2d17467fd501a06", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06e5ceb0a69ddb3e8dcec49c841f30feb326795c088de8f9544a097f89d4af407fe0763c389800a50629be1c8ef3b2acee58c85f9a365e085211c4c77916393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac5720b1131555fb0355b9116baea3e46281f6b2d5de838518852599a6ed5f15ae56971a72c4968d609827ee62683182dad7e1ea92f0b85a0250a9c2e3c90fa"} 04:46:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:46:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:46:53 INFO - Contents: 04:46:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a65191e5a896a6cefa06e4336c19b2107ba57ee889c1cc1e940b36924af1907dc38589a794a19df17212364a31dc85c344db569bc8fef0a2e907a20c23cf1a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c6e48b220ea1978376cc68f92f4ab7793bc7164a258dfd7cfc0ca4e19df43a603c24076f2f15dd80e9f936755cbb1e1a157ca1f45ec33d6c2d17467fd501a06", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06e5ceb0a69ddb3e8dcec49c841f30feb326795c088de8f9544a097f89d4af407fe0763c389800a50629be1c8ef3b2acee58c85f9a365e085211c4c77916393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac5720b1131555fb0355b9116baea3e46281f6b2d5de838518852599a6ed5f15ae56971a72c4968d609827ee62683182dad7e1ea92f0b85a0250a9c2e3c90fa"} 04:46:53 INFO - Running post-run listener: copy_logs_to_upload_dir 04:46:53 INFO - Copying logs to upload dir... 04:46:53 INFO - mkdir: /builds/slave/test/build/upload/logs 04:46:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.654848 ========= master_lag: 3.69 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-04 04:46:57.431670) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:57.438931) ========= 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.lqquICQLF0/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.TtJybmi269/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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/9a65191e5a896a6cefa06e4336c19b2107ba57ee889c1cc1e940b36924af1907dc38589a794a19df17212364a31dc85c344db569bc8fef0a2e907a20c23cf1a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c6e48b220ea1978376cc68f92f4ab7793bc7164a258dfd7cfc0ca4e19df43a603c24076f2f15dd80e9f936755cbb1e1a157ca1f45ec33d6c2d17467fd501a06", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06e5ceb0a69ddb3e8dcec49c841f30feb326795c088de8f9544a097f89d4af407fe0763c389800a50629be1c8ef3b2acee58c85f9a365e085211c4c77916393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac5720b1131555fb0355b9116baea3e46281f6b2d5de838518852599a6ed5f15ae56971a72c4968d609827ee62683182dad7e1ea92f0b85a0250a9c2e3c90fa"} build_url:https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011103 build_url: 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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/9a65191e5a896a6cefa06e4336c19b2107ba57ee889c1cc1e940b36924af1907dc38589a794a19df17212364a31dc85c344db569bc8fef0a2e907a20c23cf1a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c6e48b220ea1978376cc68f92f4ab7793bc7164a258dfd7cfc0ca4e19df43a603c24076f2f15dd80e9f936755cbb1e1a157ca1f45ec33d6c2d17467fd501a06", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06e5ceb0a69ddb3e8dcec49c841f30feb326795c088de8f9544a097f89d4af407fe0763c389800a50629be1c8ef3b2acee58c85f9a365e085211c4c77916393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cac5720b1131555fb0355b9116baea3e46281f6b2d5de838518852599a6ed5f15ae56971a72c4968d609827ee62683182dad7e1ea92f0b85a0250a9c2e3c90fa"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:57.475886) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:57.476203) ========= 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.lqquICQLF0/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.TtJybmi269/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.004979 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:57.529215) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 04:46:57.532536) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 04:46:57.532852) ========= ========= Total master_lag: 3.84 =========