builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0299 starttime: 1485824317.08 results: success (0) buildid: 20170130154609 builduid: cfd88eb01723445fab04149d11dbf6a4 revision: 1fe66bd0efba89df59d2046e8c91418eb5ae10b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.080341) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.080742) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.081012) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.106911) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.107221) ========= 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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-30 16:58:37-- 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% 66.0M=0s 2017-01-30 16:58:37 (66.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083793 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.214880) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.215183) ========= 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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.025454 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:37.266934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-30 16:58:37.269300) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1fe66bd0efba89df59d2046e8c91418eb5ae10b8 --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 1fe66bd0efba89df59d2046e8c91418eb5ae10b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.t7RlffsAaJ/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.cwIQK2XG6t/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-30 16:58:37,380 truncating revision to first 12 chars 2017-01-30 16:58:37,380 Setting DEBUG logging. 2017-01-30 16:58:37,380 attempt 1/10 2017-01-30 16:58:37,380 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1fe66bd0efba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-30 16:58:38,950 unpacking tar archive at: mozilla-central-1fe66bd0efba/testing/mozharness/ program finished with exit code 0 elapsedTime=1.844797 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-30 16:58:39.137231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:39.137550) ========= script_repo_revision: 1fe66bd0efba89df59d2046e8c91418eb5ae10b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:39.137962) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:39.142254) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 16:58:39.163470) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-30 16:58:39.163805) ========= /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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:58:39 INFO - MultiFileLogger online at 20170130 16:58:39 in /builds/slave/test 16:58:39 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 16:58:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:58:39 INFO - {'append_to_log': False, 16:58:39 INFO - 'base_work_dir': '/builds/slave/test', 16:58:39 INFO - 'blob_upload_branch': 'Firefox', 16:58:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:58:39 INFO - 'branch': 'Firefox', 16:58:39 INFO - 'buildbot_json_path': 'buildprops.json', 16:58:39 INFO - 'config_files': ('talos/mac_config.py',), 16:58:39 INFO - 'default_actions': ('clobber', 16:58:39 INFO - 'read-buildbot-config', 16:58:39 INFO - 'download-and-extract', 16:58:39 INFO - 'populate-webroot', 16:58:39 INFO - 'create-virtualenv', 16:58:39 INFO - 'install', 16:58:39 INFO - 'run-tests'), 16:58:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:58:39 INFO - 'download_minidump_stackwalk': True, 16:58:39 INFO - 'download_symbols': 'ondemand', 16:58:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:58:39 INFO - 'tooltool.py': '/tools/tooltool.py', 16:58:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:58:39 INFO - '/tools/misc-python/virtualenv.py')}, 16:58:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:58:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:58:39 INFO - 'installer_path': 'installer.exe', 16:58:39 INFO - 'log_level': 'info', 16:58:39 INFO - 'log_name': 'talos', 16:58:39 INFO - 'log_to_console': True, 16:58:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:58:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:58:39 INFO - 'opt_config_files': (), 16:58:39 INFO - 'pip_index': False, 16:58:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:58:39 INFO - 'cmd': ('bash', 16:58:39 INFO - '-c', 16:58:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:58:39 INFO - 'enabled': True, 16:58:39 INFO - 'halt_on_failure': False, 16:58:39 INFO - 'name': 'check_screen_resolution'},), 16:58:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:58:39 INFO - 'cmd': ('bash', 16:58:39 INFO - '-c', 16:58:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:58:39 INFO - 'enabled': True, 16:58:39 INFO - 'halt_on_failure': False, 16:58:39 INFO - 'name': 'check_screen_resolution'},), 16:58:39 INFO - 'run_cmd_checks_enabled': True, 16:58:39 INFO - 'sps_profile': False, 16:58:39 INFO - 'sps_profile_interval': 0, 16:58:39 INFO - 'suite': 'dromaeojs-e10s', 16:58:39 INFO - 'system_bits': '32', 16:58:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:58:39 INFO - 'title': 't-yosemite-r7-0299', 16:58:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:58:39 INFO - 'use_talos_json': True, 16:58:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:58:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:58:39 INFO - 'work_dir': 'build'} 16:58:39 INFO - [mozharness: 2017-01-31 00:58:39.380756Z] Running clobber step. 16:58:39 INFO - Running pre-action listener: _resource_record_pre_action 16:58:39 INFO - Running main action method: clobber 16:58:39 INFO - rmtree: /builds/slave/test/build 16:58:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:58:41 INFO - Running post-action listener: _resource_record_post_action 16:58:41 INFO - [mozharness: 2017-01-31 00:58:41.890757Z] Finished clobber step (success) 16:58:41 INFO - [mozharness: 2017-01-31 00:58:41.890993Z] Running read-buildbot-config step. 16:58:41 INFO - Running pre-action listener: _resource_record_pre_action 16:58:41 INFO - Running main action method: read_buildbot_config 16:58:41 INFO - Using buildbot properties: 16:58:41 INFO - { 16:58:41 INFO - "project": "", 16:58:41 INFO - "product": "firefox", 16:58:41 INFO - "script_repo_revision": "production", 16:58:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:58:41 INFO - "repository": "", 16:58:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:58:41 INFO - "buildid": "20170130154609", 16:58:41 INFO - "pgo_build": "False", 16:58:41 INFO - "basedir": "/builds/slave/test", 16:58:41 INFO - "buildnumber": 31, 16:58:41 INFO - "slavename": "t-yosemite-r7-0299", 16:58:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:58:41 INFO - "platform": "yosemite_r7", 16:58:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:58:41 INFO - "branch": "mozilla-central", 16:58:41 INFO - "repo_path": "mozilla-central", 16:58:41 INFO - "revision": "1fe66bd0efba89df59d2046e8c91418eb5ae10b8", 16:58:41 INFO - "stage_platform": "macosx64", 16:58:41 INFO - "builduid": "cfd88eb01723445fab04149d11dbf6a4", 16:58:41 INFO - "slavebuilddir": "test" 16:58:41 INFO - } 16:58:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 16:58:41 INFO - Running post-action listener: _resource_record_post_action 16:58:41 INFO - [mozharness: 2017-01-31 00:58:41.896608Z] Finished read-buildbot-config step (success) 16:58:41 INFO - [mozharness: 2017-01-31 00:58:41.896789Z] Running download-and-extract step. 16:58:41 INFO - Running pre-action listener: _resource_record_pre_action 16:58:41 INFO - Running main action method: download_and_extract 16:58:41 INFO - mkdir: /builds/slave/test/build/tests 16:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:58:41 INFO - trying https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 16:58:41 INFO - Downloading https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 16:58:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 16:58:43 INFO - Downloaded 1199 bytes. 16:58:43 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 16:58:43 INFO - Using the following test package requirements: 16:58:43 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 16:58:43 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 16:58:43 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 16:58:43 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'jsshell-mac.zip'], 16:58:43 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 16:58:43 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 16:58:43 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 16:58:43 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 16:58:43 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 16:58:43 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 16:58:43 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 16:58:43 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:58:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 16:58:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:58:43 INFO - Fetch https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 16:58:44 INFO - Expected file size: 16368055 16:58:44 INFO - Obtained file size: 16368055 16:58:50 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:58:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 16:58:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:58:50 INFO - Fetch https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 16:58:51 INFO - Expected file size: 16368055 16:58:51 INFO - Obtained file size: 16368055 16:58:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 16:58:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:58:58 INFO - Fetch https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 16:58:59 INFO - Expected file size: 14403454 16:58:59 INFO - Obtained file size: 14403454 16:58:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:58:59 INFO - trying https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 16:58:59 INFO - Downloading https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 16:58:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 16:59:01 INFO - Downloaded 70184208 bytes. 16:59:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 16:59:01 INFO - mkdir: /builds/slave/test/properties 16:59:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:59:01 INFO - Writing to file /builds/slave/test/properties/build_url 16:59:01 INFO - Contents: 16:59:01 INFO - build_url:https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 16:59:02 INFO - Running post-action listener: _resource_record_post_action 16:59:02 INFO - Running post-action listener: set_extra_try_arguments 16:59:02 INFO - [mozharness: 2017-01-31 00:59:02.467907Z] Finished download-and-extract step (success) 16:59:02 INFO - [mozharness: 2017-01-31 00:59:02.468028Z] Running populate-webroot step. 16:59:02 INFO - Running pre-action listener: _resource_record_pre_action 16:59:02 INFO - Running main action method: populate_webroot 16:59:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:59:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:59:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:59:02 INFO - '--tppagecycles', 16:59:02 INFO - '7'], 16:59:02 INFO - 'tests': ['tsvgm']}}, 16:59:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:59:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:59:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:59:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:59:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:59:02 INFO - 'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:59:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:59:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:59:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:59:02 INFO - 'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['damp', 'tps']}, 16:59:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:59:02 INFO - 'tests': ['damp', 'tps']}, 16:59:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['dromaeo_dom']}, 16:59:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:59:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['basic_compositor_video']}, 16:59:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:59:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['a11yr', 16:59:02 INFO - 'ts_paint', 16:59:02 INFO - 'tpaint', 16:59:02 INFO - 'sessionrestore', 16:59:02 INFO - 'sessionrestore_no_auto_restore', 16:59:02 INFO - 'tabpaint']}, 16:59:02 INFO - 'other-e10s': {'tests': ['a11yr', 16:59:02 INFO - 'ts_paint', 16:59:02 INFO - 'tpaint', 16:59:02 INFO - 'sessionrestore', 16:59:02 INFO - 'sessionrestore_no_auto_restore', 16:59:02 INFO - 'tabpaint']}, 16:59:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:59:02 INFO - 'ts_paint', 16:59:02 INFO - 'tpaint', 16:59:02 INFO - 'sessionrestore', 16:59:02 INFO - 'sessionrestore_no_auto_restore', 16:59:02 INFO - 'tabpaint']}, 16:59:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:59:02 INFO - 'ts_paint', 16:59:02 INFO - 'tpaint', 16:59:02 INFO - 'sessionrestore', 16:59:02 INFO - 'sessionrestore_no_auto_restore', 16:59:02 INFO - 'tabpaint']}, 16:59:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['a11yr', 16:59:02 INFO - 'ts_paint', 16:59:02 INFO - 'tpaint', 16:59:02 INFO - 'sessionrestore', 16:59:02 INFO - 'sessionrestore_no_auto_restore', 16:59:02 INFO - 'tabpaint']}, 16:59:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['a11yr', 16:59:02 INFO - 'ts_paint', 16:59:02 INFO - 'tpaint', 16:59:02 INFO - 'sessionrestore', 16:59:02 INFO - 'sessionrestore_no_auto_restore', 16:59:02 INFO - 'tabpaint']}, 16:59:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['tsvgx', 16:59:02 INFO - 'tsvgr_opacity', 16:59:02 INFO - 'tart', 16:59:02 INFO - 'tscrollx', 16:59:02 INFO - 'cart', 16:59:02 INFO - 'tsvg_static']}, 16:59:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:59:02 INFO - 'tsvgr_opacity', 16:59:02 INFO - 'tart', 16:59:02 INFO - 'tscrollx', 16:59:02 INFO - 'cart', 16:59:02 INFO - 'tsvg_static']}, 16:59:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:59:02 INFO - 'talos_options': ['--disable-e10s'], 16:59:02 INFO - 'tests': ['tp5o']}, 16:59:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:59:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:59:02 INFO - 'talos_options': ['--disable-e10s', 16:59:02 INFO - '--xperf_path', 16:59:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:59:02 INFO - 'tests': ['tp5n']}, 16:59:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:59:02 INFO - 'talos_options': ['--xperf_path', 16:59:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:59:02 INFO - 'tests': ['tp5n']}}, 16:59:02 INFO - 'talos.zip': {'path': '', 16:59:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:59:02 INFO - Running post-action listener: _resource_record_post_action 16:59:02 INFO - [mozharness: 2017-01-31 00:59:02.486699Z] Finished populate-webroot step (success) 16:59:02 INFO - [mozharness: 2017-01-31 00:59:02.486833Z] Running create-virtualenv step. 16:59:02 INFO - Running pre-action listener: _resource_record_pre_action 16:59:02 INFO - Running main action method: create_virtualenv 16:59:02 INFO - Creating virtualenv /builds/slave/test/build/venv 16:59:02 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 16:59:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:59:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:59:02 INFO - Using real prefix '/tools/python27' 16:59:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:59:03 INFO - Installing distribute.............................................................................................................................................................................................done. 16:59:06 INFO - Installing pip.................done. 16:59:06 INFO - Return code: 0 16:59:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:59:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:59:06 INFO - Reading from file tmpfile_stdout 16:59:06 INFO - Output received: 16:59:06 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) 16:59:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:06 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 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:06 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 16:59:06 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 16:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:06 INFO - 'HOME': '/Users/cltbld', 16:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:06 INFO - 'LOGNAME': 'cltbld', 16:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:06 INFO - 'MOZ_NO_REMOTE': '1', 16:59:06 INFO - 'NO_EM_RESTART': '1', 16:59:06 INFO - 'PAGER': '/bin/cat', 16:59:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:06 INFO - 'PWD': '/builds/slave/test', 16:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:06 INFO - 'SHELL': '/bin/bash', 16:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:06 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:06 INFO - 'USER': 'cltbld', 16:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:06 INFO - 'XPC_FLAGS': '0x0', 16:59:06 INFO - 'XPC_SERVICE_NAME': '0', 16:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:06 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 16:59:06 INFO - Cleaning up... 16:59:06 INFO - Return code: 0 16:59:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:06 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 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:06 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 16:59:06 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 16:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:06 INFO - 'HOME': '/Users/cltbld', 16:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:06 INFO - 'LOGNAME': 'cltbld', 16:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:06 INFO - 'MOZ_NO_REMOTE': '1', 16:59:06 INFO - 'NO_EM_RESTART': '1', 16:59:06 INFO - 'PAGER': '/bin/cat', 16:59:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:06 INFO - 'PWD': '/builds/slave/test', 16:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:06 INFO - 'SHELL': '/bin/bash', 16:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:06 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:06 INFO - 'USER': 'cltbld', 16:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:06 INFO - 'XPC_FLAGS': '0x0', 16:59:06 INFO - 'XPC_SERVICE_NAME': '0', 16:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:06 INFO - Downloading/unpacking psutil>=3.1.1 16:59:06 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 16:59:06 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 16:59:06 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 16:59:06 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 16:59:06 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 16:59:06 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 16:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:59:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:59:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:59:10 INFO - Installing collected packages: psutil 16:59:10 INFO - Running setup.py install for psutil 16:59:10 INFO - building 'psutil._psutil_osx' extension 16:59:10 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 16:59:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:59:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:59:11 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 16:59:11 INFO - building 'psutil._psutil_posix' extension 16:59:11 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 16:59:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:59:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:59:11 INFO - ^ 16:59:11 INFO - 1 warning generated. 16:59:11 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 16:59:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:59:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:59:11 INFO - Successfully installed psutil 16:59:11 INFO - Cleaning up... 16:59:11 INFO - Return code: 0 16:59:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:59:11 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')]} 16:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:11 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')]} 16:59:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:11 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 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:11 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 16:59:11 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 16:59:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:11 INFO - 'HOME': '/Users/cltbld', 16:59:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:11 INFO - 'LOGNAME': 'cltbld', 16:59:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:11 INFO - 'MOZ_NO_REMOTE': '1', 16:59:11 INFO - 'NO_EM_RESTART': '1', 16:59:11 INFO - 'PAGER': '/bin/cat', 16:59:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:11 INFO - 'PWD': '/builds/slave/test', 16:59:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:11 INFO - 'SHELL': '/bin/bash', 16:59:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:11 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:11 INFO - 'USER': 'cltbld', 16:59:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:11 INFO - 'XPC_FLAGS': '0x0', 16:59:11 INFO - 'XPC_SERVICE_NAME': '0', 16:59:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:59:11 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 16:59:11 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 16:59:11 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 16:59:11 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 16:59:11 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 16:59:11 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 16:59:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:59:15 INFO - Installing collected packages: mozsystemmonitor 16:59:15 INFO - Running setup.py install for mozsystemmonitor 16:59:15 INFO - Successfully installed mozsystemmonitor 16:59:15 INFO - Cleaning up... 16:59:15 INFO - Return code: 0 16:59:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:59:15 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')]} 16:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:15 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')]} 16:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:15 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 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:15 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 16:59:15 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 16:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:15 INFO - 'HOME': '/Users/cltbld', 16:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:15 INFO - 'LOGNAME': 'cltbld', 16:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:15 INFO - 'MOZ_NO_REMOTE': '1', 16:59:15 INFO - 'NO_EM_RESTART': '1', 16:59:15 INFO - 'PAGER': '/bin/cat', 16:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:15 INFO - 'PWD': '/builds/slave/test', 16:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:15 INFO - 'SHELL': '/bin/bash', 16:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:15 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:15 INFO - 'USER': 'cltbld', 16:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:15 INFO - 'XPC_FLAGS': '0x0', 16:59:15 INFO - 'XPC_SERVICE_NAME': '0', 16:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:16 INFO - Downloading/unpacking jsonschema==2.5.1 16:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:59:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:59:18 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 16:59:18 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 16:59:18 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 16:59:18 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 16:59:18 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 16:59:18 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 16:59:19 INFO - Downloading functools32-3.2.3-2.tar.gz 16:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:59:19 INFO - warning: no files found matching '*.txt' 16:59:19 INFO - no previously-included directories found matching 'build' 16:59:19 INFO - no previously-included directories found matching 'dist' 16:59:19 INFO - no previously-included directories found matching '.git*' 16:59:19 INFO - Installing collected packages: jsonschema, functools32 16:59:19 INFO - Running setup.py install for functools32 16:59:19 INFO - warning: no files found matching '*.txt' 16:59:19 INFO - no previously-included directories found matching 'build' 16:59:19 INFO - no previously-included directories found matching 'dist' 16:59:19 INFO - no previously-included directories found matching '.git*' 16:59:19 INFO - Successfully installed jsonschema functools32 16:59:19 INFO - Cleaning up... 16:59:19 INFO - Return code: 0 16:59:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:19 INFO - 'HOME': '/Users/cltbld', 16:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:19 INFO - 'LOGNAME': 'cltbld', 16:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:19 INFO - 'MOZ_NO_REMOTE': '1', 16:59:19 INFO - 'NO_EM_RESTART': '1', 16:59:19 INFO - 'PAGER': '/bin/cat', 16:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:19 INFO - 'PWD': '/builds/slave/test', 16:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:19 INFO - 'SHELL': '/bin/bash', 16:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:19 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:19 INFO - 'USER': 'cltbld', 16:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:19 INFO - 'XPC_FLAGS': '0x0', 16:59:19 INFO - 'XPC_SERVICE_NAME': '0', 16:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:59:19 INFO - Cleaning up... 16:59:19 INFO - Return code: 0 16:59:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:19 INFO - 'HOME': '/Users/cltbld', 16:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:19 INFO - 'LOGNAME': 'cltbld', 16:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:19 INFO - 'MOZ_NO_REMOTE': '1', 16:59:19 INFO - 'NO_EM_RESTART': '1', 16:59:19 INFO - 'PAGER': '/bin/cat', 16:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:19 INFO - 'PWD': '/builds/slave/test', 16:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:19 INFO - 'SHELL': '/bin/bash', 16:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:19 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:19 INFO - 'USER': 'cltbld', 16:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:19 INFO - 'XPC_FLAGS': '0x0', 16:59:19 INFO - 'XPC_SERVICE_NAME': '0', 16:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:22 INFO - Downloading blobuploader-1.2.4.tar.gz 16:59:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:59:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:59: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 16:59: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 16:59: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 16:59: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 16:59: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 16:59: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 16:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:59:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:23 INFO - Downloading docopt-0.6.1.tar.gz 16:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:59:23 INFO - Installing collected packages: blobuploader, requests, docopt 16:59:23 INFO - Running setup.py install for blobuploader 16:59:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:59:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:59:24 INFO - Running setup.py install for requests 16:59:24 INFO - Running setup.py install for docopt 16:59:24 INFO - Successfully installed blobuploader requests docopt 16:59:24 INFO - Cleaning up... 16:59:24 INFO - Return code: 0 16:59:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:24 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 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:24 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 16:59:24 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 16:59:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:24 INFO - 'HOME': '/Users/cltbld', 16:59:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:24 INFO - 'LOGNAME': 'cltbld', 16:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:24 INFO - 'MOZ_NO_REMOTE': '1', 16:59:24 INFO - 'NO_EM_RESTART': '1', 16:59:24 INFO - 'PAGER': '/bin/cat', 16:59:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:24 INFO - 'PWD': '/builds/slave/test', 16:59:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:24 INFO - 'SHELL': '/bin/bash', 16:59:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:24 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:24 INFO - 'USER': 'cltbld', 16:59:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:24 INFO - 'XPC_FLAGS': '0x0', 16:59:24 INFO - 'XPC_SERVICE_NAME': '0', 16:59:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-IBRVWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-4aHRuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-HQOnQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-WVVV0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-8uOs2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Mu4g76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Y1IY0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-sfmyul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-0DH5iU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:59:25 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-_WrWE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:59:26 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-HMipqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:59:26 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-bqGeek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:59:26 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-MeKzUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:59:26 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-zk_JWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:26 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-jKLlx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:59:26 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Uc2h3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:59:26 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-7GrGUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:59:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:59:26 INFO - Running setup.py install for manifestparser 16:59:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:59:26 INFO - Running setup.py install for mozcrash 16:59:27 INFO - Running setup.py install for mozdebug 16:59:27 INFO - Running setup.py install for mozdevice 16:59:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Running setup.py install for mozfile 16:59:27 INFO - Running setup.py install for mozhttpd 16:59:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Running setup.py install for mozinfo 16:59:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Running setup.py install for mozInstall 16:59:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:59:27 INFO - Running setup.py install for mozleak 16:59:28 INFO - Running setup.py install for mozlog 16:59:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:59:28 INFO - Running setup.py install for moznetwork 16:59:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:59:28 INFO - Running setup.py install for mozprocess 16:59:28 INFO - Running setup.py install for mozprofile 16:59:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:59:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:59:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:59:28 INFO - Running setup.py install for mozrunner 16:59:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:59:28 INFO - Running setup.py install for mozscreenshot 16:59:29 INFO - Running setup.py install for moztest 16:59:29 INFO - Running setup.py install for mozversion 16:59:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:59:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:59:29 INFO - Cleaning up... 16:59:29 INFO - Return code: 0 16:59:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59: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')]} 16:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59: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')]} 16:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:30 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 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:30 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 16:59:30 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 16:59:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:30 INFO - 'HOME': '/Users/cltbld', 16:59:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:30 INFO - 'LOGNAME': 'cltbld', 16:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:30 INFO - 'MOZ_NO_REMOTE': '1', 16:59:30 INFO - 'NO_EM_RESTART': '1', 16:59:30 INFO - 'PAGER': '/bin/cat', 16:59:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:30 INFO - 'PWD': '/builds/slave/test', 16:59:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:30 INFO - 'SHELL': '/bin/bash', 16:59:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:30 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:30 INFO - 'USER': 'cltbld', 16:59:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:30 INFO - 'XPC_FLAGS': '0x0', 16:59:30 INFO - 'XPC_SERVICE_NAME': '0', 16:59:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:59:30 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-DFDB9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:59:30 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-a1nlxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:59:30 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Od6T3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:59:30 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-85hfFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:59:30 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-c9fQHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:59:30 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-BZD20F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:59:31 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)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-eRJwO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:59:31 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)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-1lDffR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:59:31 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)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Nlu9Q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-R60C4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-yEeCfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-eubfDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-H6LlYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-7G79Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:31 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-k7BxNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:32 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)) 16:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:59:32 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-n7WxT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:59:32 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)) 16:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:59:32 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-CzBhRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:59:32 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)) 16:59:32 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)) 16:59:32 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)) 16:59:32 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)) 16:59:32 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)) 16:59:32 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)) 16:59:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:59: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 16:59: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 16:59: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 16:59: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 16:59: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 16:59: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 16:59:35 INFO - Downloading blessings-1.6.tar.gz 16:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:59:35 INFO - Installing collected packages: blessings 16:59:35 INFO - Running setup.py install for blessings 16:59:35 INFO - Successfully installed blessings 16:59:35 INFO - Cleaning up... 16:59:35 INFO - Return code: 0 16:59:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:59:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:59:35 INFO - Reading from file tmpfile_stdout 16:59:35 INFO - Current package versions: 16:59:35 INFO - blessings == 1.6 16:59:35 INFO - blobuploader == 1.2.4 16:59:35 INFO - docopt == 0.6.1 16:59:35 INFO - functools32 == 3.2.3-2 16:59:35 INFO - jsonschema == 2.5.1 16:59:35 INFO - manifestparser == 1.1 16:59:35 INFO - mozInstall == 1.12 16:59:35 INFO - mozcrash == 1.0 16:59:35 INFO - mozdebug == 0.1 16:59:35 INFO - mozdevice == 0.49 16:59:35 INFO - mozfile == 1.2 16:59:35 INFO - mozhttpd == 0.7 16:59:35 INFO - mozinfo == 0.9 16:59:35 INFO - mozleak == 0.1 16:59:35 INFO - mozlog == 3.4 16:59:35 INFO - moznetwork == 0.27 16:59:35 INFO - mozprocess == 0.24 16:59:35 INFO - mozprofile == 0.28 16:59:35 INFO - mozrunner == 6.13 16:59:35 INFO - mozscreenshot == 0.1 16:59:35 INFO - mozsystemmonitor == 0.3 16:59:35 INFO - moztest == 0.8 16:59:35 INFO - mozversion == 1.4 16:59:35 INFO - psutil == 3.1.1 16:59:35 INFO - requests == 1.2.3 16:59:35 INFO - wsgiref == 0.1.2 16:59:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:59:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:35 INFO - 'HOME': '/Users/cltbld', 16:59:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:35 INFO - 'LOGNAME': 'cltbld', 16:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:35 INFO - 'MOZ_NO_REMOTE': '1', 16:59:35 INFO - 'NO_EM_RESTART': '1', 16:59:35 INFO - 'PAGER': '/bin/cat', 16:59:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:35 INFO - 'PWD': '/builds/slave/test', 16:59:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:35 INFO - 'SHELL': '/bin/bash', 16:59:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:35 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:35 INFO - 'USER': 'cltbld', 16:59:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:35 INFO - 'XPC_FLAGS': '0x0', 16:59:35 INFO - 'XPC_SERVICE_NAME': '0', 16:59:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 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)) 16:59:36 INFO - Cleaning up... 16:59:36 INFO - Return code: 0 16:59:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db96b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443c9b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, '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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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 16:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:36 INFO - 'HOME': '/Users/cltbld', 16:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:36 INFO - 'LOGNAME': 'cltbld', 16:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:36 INFO - 'MOZ_NO_REMOTE': '1', 16:59:36 INFO - 'NO_EM_RESTART': '1', 16:59:36 INFO - 'PAGER': '/bin/cat', 16:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:36 INFO - 'PWD': '/builds/slave/test', 16:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:36 INFO - 'SHELL': '/bin/bash', 16:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:36 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:36 INFO - 'USER': 'cltbld', 16:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:36 INFO - 'XPC_FLAGS': '0x0', 16:59:36 INFO - 'XPC_SERVICE_NAME': '0', 16:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:59:36 INFO - Cleaning up... 16:59:36 INFO - Return code: 0 16:59:36 INFO - Running post-action listener: _resource_record_post_action 16:59:36 INFO - Running post-action listener: _start_resource_monitoring 16:59:36 INFO - Starting resource monitoring. 16:59:36 INFO - [mozharness: 2017-01-31 00:59:36.516042Z] Finished create-virtualenv step (success) 16:59:36 INFO - [mozharness: 2017-01-31 00:59:36.517076Z] Running install step. 16:59:36 INFO - Running pre-action listener: _resource_record_pre_action 16:59:36 INFO - Running main action method: install 16:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:59:36 INFO - Reading from file tmpfile_stdout 16:59:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:59:36 INFO - Reading from file tmpfile_stdout 16:59:36 INFO - Output received: 16:59:36 INFO - Usage: mozinstall [options] installer 16:59:36 INFO - Options: 16:59:36 INFO - -h, --help show this help message and exit 16:59:36 INFO - -d DEST, --destination=DEST 16:59:36 INFO - Directory to install application into. [default: 16:59:36 INFO - "/builds/slave/test"] 16:59:36 INFO - --app=APP Application being installed. [default: firefox] 16:59:36 INFO - mkdir: /builds/slave/test/build/application 16:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:59:59 INFO - Reading from file tmpfile_stdout 16:59:59 INFO - Output received: 16:59:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:59:59 INFO - Running post-action listener: _resource_record_post_action 16:59:59 INFO - [mozharness: 2017-01-31 00:59:59.039171Z] Finished install step (success) 16:59:59 INFO - [mozharness: 2017-01-31 00:59:59.039281Z] Running run-tests step. 16:59:59 INFO - Running pre-action listener: _resource_record_pre_action 16:59:59 INFO - Running main action method: run_tests 16:59:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:59:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:59:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:59:59 INFO - 2017-01-30 16:59:59.053 screenresolution[1626:7432] starting screenresolution argv=screenresolution get 16:59:59 INFO - 2017-01-30 16:59:59.056 screenresolution[1626:7432] Display 0: 1600x1200x32@60 16:59:59 INFO - 2017-01-30 16:59:59.062 screenresolution[1627:7435] starting screenresolution argv=screenresolution list 16:59:59 INFO - Available Modes on Display 0 16:59:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:59:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:59:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:59:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:59:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:59:59 INFO - Intel Iris: 16:59:59 INFO - Chipset Model: Intel Iris 16:59:59 INFO - Type: GPU 16:59:59 INFO - Bus: Built-In 16:59:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:59:59 INFO - Vendor: Intel (0x8086) 16:59:59 INFO - Device ID: 0x0a2e 16:59:59 INFO - Revision ID: 0x0009 16:59:59 INFO - Displays: 16:59:59 INFO - Display: 16:59:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:59:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:59:59 INFO - Display Serial Number: 1600x1200 60 16:59:59 INFO - Main Display: Yes 16:59:59 INFO - Mirror: Off 16:59:59 INFO - Online: Yes 16:59:59 INFO - Rotation: Supported 16:59:59 INFO - Return code: 0 16:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:59:59 INFO - Python 2.7.3 16:59:59 INFO - Return code: 0 16:59:59 INFO - grabbing minidump binary from tooltool 16:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:59 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 0x10d974d30>, '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 0x10d89bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d969be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:59:59 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 16:59:59 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 16:59:59 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 16:59:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:59:59 INFO - Return code: 0 16:59:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:59:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:59:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:59:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:59:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--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 16:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0299 --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 16:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t7RlffsAaJ/Render', 16:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:59 INFO - 'HOME': '/Users/cltbld', 16:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:59 INFO - 'LOGNAME': 'cltbld', 16:59:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:59:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:59 INFO - 'MOZ_NO_REMOTE': '1', 16:59:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:59:59 INFO - 'NO_EM_RESTART': '1', 16:59:59 INFO - 'PAGER': '/bin/cat', 16:59:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:59 INFO - 'PWD': '/builds/slave/test', 16:59:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:59 INFO - 'SHELL': '/bin/bash', 16:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cwIQK2XG6t/Listeners', 16:59:59 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 16:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:59 INFO - 'USER': 'cltbld', 16:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:59 INFO - 'XPC_FLAGS': '0x0', 16:59:59 INFO - 'XPC_SERVICE_NAME': '0', 16:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--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 16:59:59 INFO - mozversion application_buildid: 20170130154609 16:59:59 INFO - mozversion application_changeset: 1fe66bd0efba89df59d2046e8c91418eb5ae10b8 16:59:59 INFO - mozversion application_display_name: Nightly 16:59:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:59:59 INFO - mozversion application_name: Firefox 16:59:59 INFO - mozversion application_remotingname: firefox 16:59:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:59:59 INFO - mozversion application_vendor: Mozilla 16:59:59 INFO - mozversion application_version: 54.0a1 16:59:59 INFO - mozversion platform_buildid: 20170130154609 16:59:59 INFO - mozversion platform_changeset: 1fe66bd0efba89df59d2046e8c91418eb5ae10b8 16:59:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:59:59 INFO - mozversion platform_version: 54.0a1 16:59:59 INFO - using testdate: 1485824399 16:59:59 INFO - actual date: 1485824399 16:59:59 INFO - starting webserver on 'localhost:49240' 16:59:59 INFO - SUITE-START | Running 2 tests 16:59:59 INFO - TEST-START | dromaeo_css 16:59:59 INFO - operating with platform_type : mac_ 16:59:59 INFO - Initialising browser for dromaeo_css test... 16:59:59 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpt89jQe/profile http://localhost:49240/getInfo.html) 17:00:02 INFO - PROCESS | 1637 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:00:02 INFO - PROCESS | 1637 | 2017-01-30 17:00:02.679 plugin-container[1639:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3b, name = 'com.apple.tsm.portname' 17:00:02 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:00:02 INFO - PROCESS | 1637 | 2017-01-30 17:00:02.682 plugin-container[1639:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x921b, name = 'com.apple.CFPasteboardClient' 17:00:02 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:00:02 INFO - PROCESS | 1637 | 2017-01-30 17:00:02.682 plugin-container[1639:7599] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:00:02 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 17:00:02 INFO - PROCESS | 1637 | colorDepth:24 17:00:02 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 17:00:02 INFO - PROCESS | 1637 | __metrics 17:00:02 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3284: TypeError: this.tabs is undefined 17:00:02 INFO - PROCESS | 1637 | 1485824402842 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpt89jQe/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpt89jQe/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpt89jQe/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpt89jQe/profile/extensions/talos-powers@TalosPowersService.js:55:9 17:00:03 INFO - TEST-INFO | 1637: exit 0 17:00:03 INFO - Browser initialized. 17:00:03 INFO - Running cycle 1/1 for dromaeo_css test... 17:00:03 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpt89jQe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:00:06 INFO - PROCESS | 1640 | 2017-01-30 17:00:06.045 plugin-container[1641:7719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 17:00:06 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:00:06 INFO - PROCESS | 1640 | 2017-01-30 17:00:06.045 plugin-container[1641:7719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 17:00:06 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:00:06 INFO - PROCESS | 1640 | 2017-01-30 17:00:06.045 plugin-container[1641:7719] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:00:15 INFO - PROCESS | 1640 | RSS: Main: 234024960 17:00:15 INFO - PROCESS | 1640 | 17:03:17 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 17:03:19 INFO - PROCESS | 1640 | RSS: Main: 266862592 17:03:19 INFO - PROCESS | 1640 | 17:05:03 INFO - PROCESS | 1640 | ************************* 17:05:03 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 17:05:03 INFO - PROCESS | 1640 | 17:05:03 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:05:03 INFO - PROCESS | 1640 | Full stack: 17:05:03 INFO - PROCESS | 1640 | ************************* 17:06:21 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 17:06:22 INFO - PROCESS | 1640 | RSS: Main: 266391552 17:06:22 INFO - PROCESS | 1640 | 17:09:23 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 17:09:24 INFO - PROCESS | 1640 | RSS: Main: 277270528 17:09:24 INFO - PROCESS | 1640 | 17:12:26 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 17:12:27 INFO - PROCESS | 1640 | RSS: Main: 270270464 17:12:27 INFO - PROCESS | 1640 | 17:15:29 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 17:15:30 INFO - PROCESS | 1640 | RSS: Main: 277573632 17:15:30 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 17:18:33 INFO - PROCESS | 1640 | RSS: Main: 279334912 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | __start_tp_report 17:18:33 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 17:18:33 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 17:18:33 INFO - PROCESS | 1640 | |i|pagename|runs| 17:18:33 INFO - PROCESS | 1640 | |0;dojo.html;2810.1990932584213;3092.2887735820746;3251.170951407391;3267.869285228591;3278.6065672119335;4496.955030449694;4996.675216110959;5438.265834112393;5459.099248623972;5473.425290344516;4490.797914093857;5456.781728730856;5463.098588732864;5550.2507161533185;5585.553155747541;3700.5559332879957;4186;4978.676386034915;5185.611079169059;5236.31927849379;5037.924431133536;5108.4636113208135;5188.325517682696;5192.273081768537;5220.4779522047875;4953.653244272902;5021.14640511114;5054.039732450822;5095.898082038376;5129.948700512984;5035.320231768701;5116.437191908887;5128.512791284822;5142.51146141116;5158.871028224287;3972.682185425188;5061;5065.898682026375;5080.59355251579;5107.770150343244;5360.463953604647;5423.7830486780485;5448.536874365684;5480.068388373986;5539.612227144102;6028.668423236721;6037.215162028908;6050.667213303266;6075.2405949256345;6092.933736596078;6130.356312587159;6133.049377346519;6136.693165341715;6140.293866205354;6149.169862068608;5563.443655634485;5729.255196824428;5732.598718089777;5737.311522617271;5744.770209191627;5124.385073791094;6104.847378815476;6126.27339965311;6129.325774164928;6136.94800169746;5964.821055368346;6007.8197654070445;6013.45878870895;6031.698415079229;6048.60684055535;6086.9391306087255;6117.96941015292;6122.204113465221;6129.172561704202;6134.5092392608485;5027.346444962204;5816.8847085081725;5933.228680271537;6025.216721826135;6056.303525094582;6067.423594758491;6091.360407157274;6097.756089756449;6119.44924956756;6122.571420000646;4892.094962431962;4896.730679812645;4898.191798353232;4900.166971614763;4903.656744027884;4300.161468513672;4307.612314891675;4316.330968699794;4343.109662519176;4415.072834704683;5630.155476678449;5682.176084467812;5710.65736055838;5716.28546431696;5722.198892155102;6126.203593532812;6143.078538219303;6145.477634401037;6154.169187159676;6165.568410211242;5515.4760297771645;5627.409122042209;5631.324241091013;5634.887302253932;5658.603897727202;5218.9739051304505;5264.31563896691;5272.9736351318;5314.388845282765;5385.272988146628;6169.660668663267;6186.319504854462;6191;6198.783042593487;6201.069839523927;6123.510119190365;6139.986902161093;6140.078988151813;6149.923763341487;6155.261368635793;6083.239595050619;6099;6110.892920173631;6132.601380910227;6155.076738489262;5980.730867110903;6031.004884194059;6061.6666083365835;6068.544859135494;6094.695265236632;6080.452759251689;6110.572259941761;6110.847228819315;6114.205153330038;6117.571769976059;5751.281089863767;5842.065269556851;5862.7654893805475;5908.822735317947;5957.940420595912;5981.641101533922;5987.64074155552;5991.67045812467;5992.98119319708;6022.7289771959495;4734.313524538854;5174.767135478837;5644.266245388073;5810.215620891295;5816.82549523515;4725.834595789131;5366.838994830161;5966.3735307793795;5974;5992.370801065826;4249.46881639795;5626.606137570331;5876.1773351731645;5887.499562537061;5888.558358123072 17:18:33 INFO - PROCESS | 1640 | |1;ext.html;425.5234137765703;487.5295339996902;488.367564004614;490.31600916721163;506.6985143839414;33473.66526334739;42848.35727464087;42852.57147428529;42870.35694464578;42988.56091512679;43423.131537369234;43922.68231953039;43948.780256098755;43970.560294397044;43977.7801110994;8534.87197692035;9321.440713557191;9525.666601668943;9539.18916892065;9555.044495550459;8612.655493780241;11352.545898164064;11593.130515211351;11636.243644163102;11825.881741182564;11616.012638925702;11656.825147622792;11657.650270491857;11687.883121168807;11716.589919352824;11634.592789252378;11658.6502404928;11687.415629218505;11688.181827272094;11710.648680539598;11432.028277596415;11451.599194028253;11456.0262377698;11530.654080377602;11545.01867341277;41119.97200069992;41421.37867931984;41488.792556037326;41509;41573.96065098367;4011;4021.678265738734;4025.6175663311938;4034.3141665916864;4039.272930872442;24034.245455491193;30715;30779.230519236975;31063.378732425226;31920.840395797873;3189.282411457427;3194.2014496375905;3196.952045719316;3202.711755941976;3308.751843611739;474742;477150;483448;634960.8251958757;638720;56867.86264274691;57513.84972300614;57632.71183644055;57706;57774;61183;61408.692956534935;61476;61707.6914615424;61930.69034654888;14715.632109197142;14717.043392179474;14854;14877.33052012662;15020.849791502163;10430.530626121843;10647.733806654896;11103.888961110286;11146.386948717898;11198.272112312674;17902.731459028124;18166.455006349832;18299.634007320044;18304.359347422775;18342.266309347742;6605.70274337656;6874.072000279998;7572.356349710226;7753;7912.525248485109;756.4402342266683;827.5283088639538;828.6726742936506;831.9750407487785;854.5556310718391;28798.856005719837;30586.84706576453;30712.078637640905;30745.846270768503;31459.213519662193;1042.7549525861416;1048.2452634103433;1048.7954848804563;1076.7133275735578;1164.4876254448016;15880.047197168207;16010.679786404207;16079.839201608069;16308.26612802403;16371.590710232102;608934;610562;612558;613575;615094;59905;60633;60661;60792.08811867826;60900.086498702556;1136.909047276216;1196.7905616517075;1408.1480704173828;1420.2969530082676;1430.2634143415942;24349.477307118257;26157.869210653826;27711.722882770915;29704.514774261632;30229;51421.97156056933;51569;51785.482145178066;51845.48154518406;52033.7398313006;472380;472760;474037;475037;477380;22426.66360004634;25353.87323063373;25915.740842592088;26029.349266267738;26947.326316841452;43222.78388608037;43510.78244608884;44490.55509444864;44775.880602985184;44833.327500086874;36334;36833.169867119264;37359;37378.43932341071;37449;35899.82050089733;36060;36757.44863826991;36947.44578831266;37157.628423716495;35956.4606530897;36440.63559364479;36892.63107368892;38188.809055954545;38222.61777382302;31442.842785785924;33022.33955320928;33060.66939330672;33101;33194.66805331916;24114.87942560346;24189.911453984965;24524.50950981006;24529.75470245275;25035.87482062578 17:18:33 INFO - PROCESS | 1640 | |2;jquery.html;394.13094127448977;394.38869751884573;401.7468994533443;405.8518640696146;426.24980035138145;14746.188959607243;16493.83506164938;19609.31367402141;21020.789792102074;21038.474038149034;21055;21272.255471058517;21582.676259856074;21683.349499515;21915.890420547876;5252.947470525303;5675.063614503602;5699.515541179005;5739.827805165852;5754.597178197507;5579.191017302509;5615.382307946143;5728;5733.197352370671;5745.166950792153;5864.648121112726;5987.401259873999;5992.131140984555;6000.579959402844;6005.519558435337;5932.050871860503;5967.313758917715;5997.37027612101;6440.935590644127;6521.380468855451;5787.681677507735;5796.420357964212;5800.796972105956;5804.622699524517;5812.157237200582;13014.609561713163;13221.471141154343;13374.930005599528;13413.865861341359;13421.731565368638;2106.8735875847347;2110.472381904524;2134.957300853974;2164.7618761936174;2931.457680329146;9492.762680932963;9882.9505852471;10411.94794026025;10420.166386689047;10614.416207108607;1847.5935294235246;1852.4998250472297;1867.8692491525599;1869.5699989002605;1892.4133518609276;140870;142958.2852085733;144299.99999999895;146327;147681;11719.663724422018;11730;11912.927836494757;12028.45871935765;12289.938550307192;15226.390944362323;15228.46700365504;15510.922445387927;15533.301001454962;15548;8115.59422028908;8371.623276952552;8415.116412776693;8486.872696909551;8508.532030738244;7936.682532698743;7986.361091112697;8052.879206811903;8174.67301307941;8175.264226219669;4673.672842901033;4690.349952277021;4734.431868175772;4744.667873248908;4758.666893317504;1988.2146552112124;2036.2872994451823;2051.98974005129;2052.9794702053086;2054.250198677472;846.2848892685671;849.4648371525899;853.3685073046025;867.5402036920442;882.8587426011808;19962.90018549927;20508.38474845757;20537.48656283605;20557.383278501668;20738.75567465957;878.0867897386646;885.751989442957;899.6536333511646;907.2107266678032;911.2163539356148;10312.896871031193;10353;10659.89340106589;10692.679219623424;10712.250142490107;673676;724733;733855;746768;756181;120044;151347.24326378517;156761;160656;164922.9999999976;1000.4547521600745;1018.6587493189717;1045.487711021594;1046.2362475393006;1049.4070849969742;15904.761428578811;16610.833891661412;19820.549348928584;20027.198912043354;20056.59886802285;51918;56029.599260018826;61974.69012654908;63312.36687633249;63907.680461597396;93308;94558;94604.52697736467;94810.52594736982;94850.82939157939;7058.71765129389;7555.697772089275;7617.238276172338;8118.512889226666;8626.525541095049;9345;12373.75252494963;12882;13350.19898806036;13393;10895.836562451412;11215.102791776475;11517;11551.075913926697;11745.177837551631;9859.65491207833;11009.779804403706;11148;11223.4388280584;11296.209265351346;11372.772544549229;11567.132465065322;11759.470823812779;11766.58816941403;11843.940780296043;7383;7571.205023472677;7702.499337543042;7712.5758083305955;7811.570363630055;8924.286057115285;10353.327033742782;10642.73393165177;10799.190060745632;10970.506327215455 17:18:33 INFO - PROCESS | 1640 | |3;mootools.html;1246.7755803955286;1395.0862185268652;1412.6962703018849;1413.9434422623096;1416.6458385403648;7504.324610785028;7758.573278469696;8038.276555110039;8480.872786908194;8488.108748581395;7932.325752311061;8190.467619604721;8253.876191857127;8326.209010144043;8363.540005299707;1906.65680177568;2084.5101401170714;2238.921637742679;2287.7025986621716;2297.0926484038796;1847.436531857783;1946.980530194694;2259.48031952651;2304.6543018547186;2348.283773449097;2289.713785776778;2311.872846993415;2316.3745788637057;2317.640765681322;2318.234982455794;2274.613315736329;2279.4415368234804;2286.5541219462043;2289.645105008726;2295.598270302699;2203.294945617403;2260.706108205939;2289.3360925331635;2307.3654744945106;2308.6306191009357;6983.650817459157;7724.30481256684;8213.89112469815;8439.240468357815;8475.533845638487;5073.289739436482;5241.68549887008;5244.842654720364;5246.737663116867;5258.474152584749;3297.505374193866;3486.267883744417;3508.0528257370624;3551.271989242199;3569.036360182736;1099.5711672447671;1175.1069187417625;1211.9272843629321;1265.8544267409275;1276.757416090949;8824.691135810215;9569.90430095697;9975.40393537038;10683.83974240387;10821.242513024092;4380.969508052599;4402.119576084796;4495.572920572606;4508.391367165391;4518.367428559938;8545.529995850164;8611.741647750578;8645.610947507377;8660.696875609323;8667.783305417415;4584.908301833944;4930.2851086592245;5103.948960510347;5105.285260063594;5105.566026887756;4598.839040633562;4604.37840891482;4735.171345014677;4788.736619485892;4838.225883858636;3569.1077230692326;3630.5098811660614;3671.127724860287;3693.3351996640326;3725.720570957189;921.3734660430837;1082.9079528240031;1097.7365432296192;1162.3839365136491;1166.7549814539043;780.6487080813656;858.9613467393857;883.1212943121635;885.2386947225381;921.7879887625884;5312;5669.121286200645;5684.63049901755;5731.570132240098;5899.292084949748;970.752458123174;970.84466485362;976.4190306767462;996.14829320931;1026.1431704526653;3165.920851978719;3523.947140792839;3536.2927414516685;3715.108373990223;3731.533558305212;9527.809443811086;9760.902390976142;9850.310478266452;9862.309638325392;9968.651097211563;7728.729494467667;9810.65662701802;9841.55712992917;9845.310828242098;9866.161376282953;7330.193678695339;7625.847483050419;7658.578778167142;7678.963339949147;7784.2994130527395;4802.903941921072;5530.723463826712;5681.9147712783515;5755.424457554211;5790.276215473066;8809.691660791841;8945.865812012955;8955.59699813497;9068.637254509746;9112.863307050533;8853.29173666118;9137.908620913706;9167.908320916973;9244.168024877525;9292.674756383494;6511.34886511345;6966.442684585119;7270.272972702687;7270.418366530771;7288.635568221462;6938.548994315555;7782.688692452239;7806.219378062148;7854.136045035156;7877.842443151219;6385.8722825544155;7087.22040575526;7117.964410177916;7138.321859423347;7157.355837974399;6882;6928.376446119873;6960.47796415281;6980.6509674515055;7003.964980175067;6276.968615156895;6317.873642527032;6380;6396.616203027833;6405.103285540122;4818.734969576707;4905.6075513958085;4929.383827021622;4996.650234483696;5100.311457953128;4896.11869863428;4921.4832442593015;4973.776180071978;5005.224190250517;5066 17:18:33 INFO - PROCESS | 1640 | |4;prototype.html;355.70831917827377;358.48199351936455;369.4476757247914;370.449881925341;376.42031271841364;4916.58209052231;5076.46697096805;5100.719460429675;5180.378354597449;5339.7330133493315;5076.314697515834;5119.846404607859;5274.287971123907;5327.7336133193285;5330.626856120063;4176.6843444314945;4209.810558524866;4227.471566054243;4234.618884300412;4250.213710463574;3389.440742277521;3533.522974398462;3890.9416358754643;4211.305134652794;4234.1320029394055;3593.5508061492314;3975.7415767975135;4104.384342348657;4169.374593810938;4192.769397683126;4163;4171.541130475645;4176.60322269384;4178.811953462102;4181.344846022782;3128.4838001729686;3167.7307428868576;3681.9815900920594;3711.3876210425246;3735.4210097434943;4313.698041137121;5175.774417000688;5248.685078895279;5271.841844744664;5309.283246761676;4463.33050042493;4488.214562451558;4654.604358629521;4877.317175595419;4904.528641407564;5433.918491222635;5462.617616766828;5738.684372359518;5745.569082318842;5803.533498263221;3865.873769426804;3875.5155605549307;4071.5724848890736;4087.3255912774653;4148.149629325981;5387.087583907695;5640.379558248589;5969.343372229051;6002.279726432856;6041.667708276043;4185.953511622094;4207.221663992172;4210.410542524049;4214.7892605370225;4254.914901701949;3658.5426821647293;3929.1945151243926;3993.3610622300876;4000.7599544027453;4271.0492794165275;4633.7683115844075;4648.48866624671;4660.953390466119;4669.929951050737;4693.976530117396;5186.325777648882;5390.248169344978;5657.98156331856;5750.913736293959;5765.250517432707;1069.4706120470262;1145.68493664243;1162.9475324831042;1181.149572307937;1273.446050967832;842.3682238321259;858.296197118357;872.284726524244;876.6099085906837;879.2921698033017;4668.758995682092;4968.378952630921;5006.047641419849;5067.8986420271385;5077.81653888824;4487.955120448819;4856.368672072678;5170.1727723564045;5532;5560.805371811967;4171.452694410916;4314.590113939171;4338.761368124783;4684.648651351163;5052.595792336604;2543.5167318209483;2829.5189817731034;2861.7567506762157;2883.1206482023185;2944.5413080077947;4969.552740253394;5383.892322153613;5622.297212848394;5663.292088488939;5699.914501282484;4665.95334046662;5323.574114070865;5348.812791552355;5740.971295143581;5980.833737421248;4982.725950072709;5203.531682148549;6010.579259451796;6099.298580663103;6102.176206212193;5020.393474088257;5139.974300128625;5803.390643982321;5858.7070646466655;5988.580799344004;5595.720213989365;5658.886822263449;5728.226689396878;5777.595568310178;5803.709814509342;5119;5539.778408863762;6030.39696030411;6043.576949613661;6079.544034197364;3384.983075084609;3399.6600339965808;3916;3930.6462418382484;3934.075492259374;4854.563089321978;5482;5551.250581171652;5739.278216535106;5887.499562537232;4920.92618610714;5301.469853014668;5628.943710562842;5673.34756502991;5687.857803554944;5259.42146363892;5667.744951477276;5688.497061293017;5699.800506982236;5703.20155178267;5203.11547036997;5457.481539253765;5526.253955715927;5599.636023658449;5614.66991198648;5014.52362025607;5650.943490565206;5660.943390566042;5673.602847800779;5691.146328050825;4701.553352431513;5285.365756109291;5543.667379957215;5576.386597474356;5603.327600687943 17:18:33 INFO - PROCESS | 1640 | |5;yui.html;681.8022773395716;692.6052150274343;747.3983443328119;792.5799326357031;855.2260204514916;14691.191984440851;15009.024413413148;15062.322195501201;15943.202839858004;15965.521034368972;14331;14608.926955365208;14623.561293161221;14651.120932744043;15546.689066218669;854.3848429131032;894.3650008493945;895.8253140637578;897.6409436225497;908.4276905549494;685.5441131647449;705.9929400705979;877.5919197573127;878.6529320918265;880.55972013993;686.9798349451061;903.5723556780305;904.656230632362;905.9048799876017;925.4956048953337;919.5999740113073;923.64901337492;924.4222361024359;924.6255266617031;925.1165137293901;686.3788271614197;687.525607330945;688.7589343729704;689.9206591242021;690.4510913823523;12770.361481925867;14564.85435145656;14590.051646642934;15784.210789460481;15916.681666366723;13573.253471059088;13592.660183495393;13796;13800.861991380058;13889.236092014935;12780.552680656132;13071.41178646963;13102.541411050568;13142.671433214151;13222.66943326415;4371.825126994916;4376.23415902219;4421.182081314967;4445.399871017404;4588.541145885418;108831.45584272029;112604;112813;112924;114167.42916285366;10693.358398496115;10740.462976851282;10809.945950270197;10864.837027444595;10955.068819150461;21343.35969920905;21443.035063422183;21568.24511142134;21645.67531487029;21687.34937951864;7427.591482468408;7446.627668616547;7486.5508069516;7496.137944136494;7634.236576342321;4072.959270407258;4084.6323830855367;4112.992316882383;4158.771267580251;4160.958390416057;2970.3168271297723;3385.9153521161675;3493.4410494320787;3493.458513930345;3509.6314886937016;628.6793735195083;635.6980434293673;638.1958731997623;639.4756299834091;649.1042361541043;602.2832828933642;607.5109536822812;627.8493161641173;630.8296759874808;635.7393468677819;9354.391964522285;9754.561044753003;9791.314607977372;9916.851247231298;10037.297389182688;611.6421196288334;616.1927874484351;647.5564238496645;647.6761619190405;666.7664820082808;4711.053393936353;4830.975845120752;4860.9513904861205;4894.342534285771;4903.705777653352;85797.57101214578;87272.69090963641;96326.51836740771;102905;105866;74999.62500187464;83542.16457835466;87999.56000220086;89819;90728;69239.61520769556;70597.23506912368;72239;75435.62282188554;80390.79413808798;11024.834627480755;11040.779184416428;11082.279651822928;11326.83009754871;11357.204995650225;50146.247806282205;50853;52509.474905251984;52560;53649;50447.991040178254;53108.734456327475;60745.87389441348;69196;71734.28265717484;12927;12990.285534295705;13132.671683207996;13157.160786501156;13232.801507977196;12946.158499697489;14143.585641435775;14376.28118594087;14532.418703251751;15123.621909452351;13321.533746318832;13446.12378369745;13521.18872867631;13584.252866092065;14042.578722638336;13219.735605288033;13394.732105357643;13960.441582336593;14006;14052.297385130907;10873.565057397616;13186.274754888573;13363.732725345633;13499.595012149652;14007.789883151965;10908.61819836302;10912.181586380895;11089.667309980714;11114.166437517057;11404.372759498307;8942.329325300498;10422.322549034288;10835.404052776857;10842.024217820433;10915.508802104083 17:18:33 INFO - PROCESS | 1640 | __end_tp_report 17:18:33 INFO - PROCESS | 1640 | __start_cc_report 17:18:33 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,403 17:18:33 INFO - PROCESS | 1640 | __end_cc_report 17:18:33 INFO - PROCESS | 1640 | __startTimestamp1485825513741__endTimestamp 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | ------- Summary: start ------- 17:18:33 INFO - PROCESS | 1640 | Number of tests: 6 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5536.81 Median:5737.31 stddev:696.82 (12.1%) stddev-sans-first:665.32 17:18:33 INFO - PROCESS | 1640 | Values: 2810.2 3092.3 3251.2 3267.9 3278.6 4497.0 4996.7 5438.3 5459.1 5473.4 4490.8 5456.8 5463.1 5550.3 5585.6 3700.6 4186.0 4978.7 5185.6 5236.3 5037.9 5108.5 5188.3 5192.3 5220.5 4953.7 5021.1 5054.0 5095.9 5129.9 5035.3 5116.4 5128.5 5142.5 5158.9 3972.7 5061.0 5065.9 5080.6 5107.8 5360.5 5423.8 5448.5 5480.1 5539.6 6028.7 6037.2 6050.7 6075.2 6092.9 6130.4 6133.0 6136.7 6140.3 6149.2 5563.4 5729.3 5732.6 5737.3 5744.8 5124.4 6104.8 6126.3 6129.3 6136.9 5964.8 6007.8 6013.5 6031.7 6048.6 6086.9 6118.0 6122.2 6129.2 6134.5 5027.3 5816.9 5933.2 6025.2 6056.3 6067.4 6091.4 6097.8 6119.4 6122.6 4892.1 4896.7 4898.2 4900.2 4903.7 4300.2 4307.6 4316.3 4343.1 4415.1 5630.2 5682.2 5710.7 5716.3 5722.2 6126.2 6143.1 6145.5 6154.2 6165.6 5515.5 5627.4 5631.3 5634.9 5658.6 5219.0 5264.3 5273.0 5314.4 5385.3 6169.7 6186.3 6191.0 6198.8 6201.1 6123.5 6140.0 6140.1 6149.9 6155.3 6083.2 6099.0 6110.9 6132.6 6155.1 5980.7 6031.0 6061.7 6068.5 6094.7 6080.5 6110.6 6110.8 6114.2 6117.6 5751.3 5842.1 5862.8 5908.8 5957.9 5981.6 5987.6 5991.7 5993.0 6022.7 4734.3 5174.8 5644.3 5810.2 5816.8 4725.8 5366.8 5966.4 5974.0 5992.4 4249.5 5626.6 5876.2 5887.5 5888.6 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:67908.11 Median:36795.31 stddev:146144.68 (397.2%) stddev-sans-first:146466.83 17:18:33 INFO - PROCESS | 1640 | Values: 425.5 487.5 488.4 490.3 506.7 33473.7 42848.4 42852.6 42870.4 42988.6 43423.1 43922.7 43948.8 43970.6 43977.8 8534.9 9321.4 9525.7 9539.2 9555.0 8612.7 11352.5 11593.1 11636.2 11825.9 11616.0 11656.8 11657.7 11687.9 11716.6 11634.6 11658.7 11687.4 11688.2 11710.6 11432.0 11451.6 11456.0 11530.7 11545.0 41120.0 41421.4 41488.8 41509.0 41574.0 4011.0 4021.7 4025.6 4034.3 4039.3 24034.2 30715.0 30779.2 31063.4 31920.8 3189.3 3194.2 3197.0 3202.7 3308.8 474742.0 477150.0 483448.0 634960.8 638720.0 56867.9 57513.8 57632.7 57706.0 57774.0 61183.0 61408.7 61476.0 61707.7 61930.7 14715.6 14717.0 14854.0 14877.3 15020.8 10430.5 10647.7 11103.9 11146.4 11198.3 17902.7 18166.5 18299.6 18304.4 18342.3 6605.7 6874.1 7572.4 7753.0 7912.5 756.4 827.5 828.7 832.0 854.6 28798.9 30586.8 30712.1 30745.8 31459.2 1042.8 1048.2 1048.8 1076.7 1164.5 15880.0 16010.7 16079.8 16308.3 16371.6 608934.0 610562.0 612558.0 613575.0 615094.0 59905.0 60633.0 60661.0 60792.1 60900.1 1136.9 1196.8 1408.1 1420.3 1430.3 24349.5 26157.9 27711.7 29704.5 30229.0 51422.0 51569.0 51785.5 51845.5 52033.7 472380.0 472760.0 474037.0 475037.0 477380.0 22426.7 25353.9 25915.7 26029.3 26947.3 43222.8 43510.8 44490.6 44775.9 44833.3 36334.0 36833.2 37359.0 37378.4 37449.0 35899.8 36060.0 36757.4 36947.4 37157.6 35956.5 36440.6 36892.6 38188.8 38222.6 31442.8 33022.3 33060.7 33101.0 33194.7 24114.9 24189.9 24524.5 24529.8 25035.9 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:40228.74 Median:11873.72 stddev:121780.53 (1025.6%) stddev-sans-first:122085.43 17:18:33 INFO - PROCESS | 1640 | Values: 394.1 394.4 401.7 405.9 426.2 14746.2 16493.8 19609.3 21020.8 21038.5 21055.0 21272.3 21582.7 21683.3 21915.9 5252.9 5675.1 5699.5 5739.8 5754.6 5579.2 5615.4 5728.0 5733.2 5745.2 5864.6 5987.4 5992.1 6000.6 6005.5 5932.1 5967.3 5997.4 6440.9 6521.4 5787.7 5796.4 5800.8 5804.6 5812.2 13014.6 13221.5 13374.9 13413.9 13421.7 2106.9 2110.5 2135.0 2164.8 2931.5 9492.8 9883.0 10411.9 10420.2 10614.4 1847.6 1852.5 1867.9 1869.6 1892.4 140870.0 142958.3 144300.0 146327.0 147681.0 11719.7 11730.0 11912.9 12028.5 12289.9 15226.4 15228.5 15510.9 15533.3 15548.0 8115.6 8371.6 8415.1 8486.9 8508.5 7936.7 7986.4 8052.9 8174.7 8175.3 4673.7 4690.3 4734.4 4744.7 4758.7 1988.2 2036.3 2052.0 2053.0 2054.3 846.3 849.5 853.4 867.5 882.9 19962.9 20508.4 20537.5 20557.4 20738.8 878.1 885.8 899.7 907.2 911.2 10312.9 10353.0 10659.9 10692.7 10712.3 673676.0 724733.0 733855.0 746768.0 756181.0 120044.0 151347.2 156761.0 160656.0 164923.0 1000.5 1018.7 1045.5 1046.2 1049.4 15904.8 16610.8 19820.5 20027.2 20056.6 51918.0 56029.6 61974.7 63312.4 63907.7 93308.0 94558.0 94604.5 94810.5 94850.8 7058.7 7555.7 7617.2 8118.5 8626.5 9345.0 12373.8 12882.0 13350.2 13393.0 10895.8 11215.1 11517.0 11551.1 11745.2 9859.7 11009.8 11148.0 11223.4 11296.2 11372.8 11567.1 11759.5 11766.6 11843.9 7383.0 7571.2 7702.5 7712.6 7811.6 8924.3 10353.3 10642.7 10799.2 10970.5 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5294.40 Median:5675.52 stddev:2848.84 (50.2%) stddev-sans-first:2840.59 17:18:33 INFO - PROCESS | 1640 | Values: 1246.8 1395.1 1412.7 1413.9 1416.6 7504.3 7758.6 8038.3 8480.9 8488.1 7932.3 8190.5 8253.9 8326.2 8363.5 1906.7 2084.5 2238.9 2287.7 2297.1 1847.4 1947.0 2259.5 2304.7 2348.3 2289.7 2311.9 2316.4 2317.6 2318.2 2274.6 2279.4 2286.6 2289.6 2295.6 2203.3 2260.7 2289.3 2307.4 2308.6 6983.7 7724.3 8213.9 8439.2 8475.5 5073.3 5241.7 5244.8 5246.7 5258.5 3297.5 3486.3 3508.1 3551.3 3569.0 1099.6 1175.1 1211.9 1265.9 1276.8 8824.7 9569.9 9975.4 10683.8 10821.2 4381.0 4402.1 4495.6 4508.4 4518.4 8545.5 8611.7 8645.6 8660.7 8667.8 4584.9 4930.3 5103.9 5105.3 5105.6 4598.8 4604.4 4735.2 4788.7 4838.2 3569.1 3630.5 3671.1 3693.3 3725.7 921.4 1082.9 1097.7 1162.4 1166.8 780.6 859.0 883.1 885.2 921.8 5312.0 5669.1 5684.6 5731.6 5899.3 970.8 970.8 976.4 996.1 1026.1 3165.9 3523.9 3536.3 3715.1 3731.5 9527.8 9760.9 9850.3 9862.3 9968.7 7728.7 9810.7 9841.6 9845.3 9866.2 7330.2 7625.8 7658.6 7679.0 7784.3 4802.9 5530.7 5681.9 5755.4 5790.3 8809.7 8945.9 8955.6 9068.6 9112.9 8853.3 9137.9 9167.9 9244.2 9292.7 6511.3 6966.4 7270.3 7270.4 7288.6 6938.5 7782.7 7806.2 7854.1 7877.8 6385.9 7087.2 7118.0 7138.3 7157.4 6882.0 6928.4 6960.5 6980.7 7004.0 6277.0 6317.9 6380.0 6396.6 6405.1 4818.7 4905.6 4929.4 4996.7 5100.3 4896.1 4921.5 4973.8 5005.2 5066.0 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4547.27 Median:5072.11 stddev:1386.06 (27.3%) stddev-sans-first:1353.77 17:18:33 INFO - PROCESS | 1640 | Values: 355.7 358.5 369.4 370.4 376.4 4916.6 5076.5 5100.7 5180.4 5339.7 5076.3 5119.8 5274.3 5327.7 5330.6 4176.7 4209.8 4227.5 4234.6 4250.2 3389.4 3533.5 3890.9 4211.3 4234.1 3593.6 3975.7 4104.4 4169.4 4192.8 4163.0 4171.5 4176.6 4178.8 4181.3 3128.5 3167.7 3682.0 3711.4 3735.4 4313.7 5175.8 5248.7 5271.8 5309.3 4463.3 4488.2 4654.6 4877.3 4904.5 5433.9 5462.6 5738.7 5745.6 5803.5 3865.9 3875.5 4071.6 4087.3 4148.1 5387.1 5640.4 5969.3 6002.3 6041.7 4186.0 4207.2 4210.4 4214.8 4254.9 3658.5 3929.2 3993.4 4000.8 4271.0 4633.8 4648.5 4661.0 4669.9 4694.0 5186.3 5390.2 5658.0 5750.9 5765.3 1069.5 1145.7 1162.9 1181.1 1273.4 842.4 858.3 872.3 876.6 879.3 4668.8 4968.4 5006.0 5067.9 5077.8 4488.0 4856.4 5170.2 5532.0 5560.8 4171.5 4314.6 4338.8 4684.6 5052.6 2543.5 2829.5 2861.8 2883.1 2944.5 4969.6 5383.9 5622.3 5663.3 5699.9 4666.0 5323.6 5348.8 5741.0 5980.8 4982.7 5203.5 6010.6 6099.3 6102.2 5020.4 5140.0 5803.4 5858.7 5988.6 5595.7 5658.9 5728.2 5777.6 5803.7 5119.0 5539.8 6030.4 6043.6 6079.5 3385.0 3399.7 3916.0 3930.6 3934.1 4854.6 5482.0 5551.3 5739.3 5887.5 4920.9 5301.5 5628.9 5673.3 5687.9 5259.4 5667.7 5688.5 5699.8 5703.2 5203.1 5457.5 5526.3 5599.6 5614.7 5014.5 5650.9 5660.9 5673.6 5691.1 4701.6 5285.4 5543.7 5576.4 5603.3 17:18:33 INFO - PROCESS | 1640 | 17:18:33 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20137.80 Median:4159.86 stddev:28749.15 (691.1%) stddev-sans-first:28792.68 17:18:33 INFO - PROCESS | 1640 | Values: 681.8 692.6 747.4 792.6 855.2 14691.2 15009.0 15062.3 15943.2 15965.5 14331.0 14608.9 14623.6 14651.1 15546.7 854.4 894.4 895.8 897.6 908.4 685.5 706.0 877.6 878.7 880.6 687.0 903.6 904.7 905.9 925.5 919.6 923.6 924.4 924.6 925.1 686.4 687.5 688.8 689.9 690.5 12770.4 14564.9 14590.1 15784.2 15916.7 13573.3 13592.7 13796.0 13800.9 13889.2 12780.6 13071.4 13102.5 13142.7 13222.7 4371.8 4376.2 4421.2 4445.4 4588.5 108831.5 112604.0 112813.0 112924.0 114167.4 10693.4 10740.5 10809.9 10864.8 10955.1 21343.4 21443.0 21568.2 21645.7 21687.3 7427.6 7446.6 7486.6 7496.1 7634.2 4073.0 4084.6 4113.0 4158.8 4161.0 2970.3 3385.9 3493.4 3493.5 3509.6 628.7 635.7 638.2 639.5 649.1 602.3 607.5 627.8 630.8 635.7 9354.4 9754.6 9791.3 9916.9 10037.3 611.6 616.2 647.6 647.7 666.8 4711.1 4831.0 4861.0 4894.3 4903.7 85797.6 87272.7 96326.5 102905.0 105866.0 74999.6 83542.2 87999.6 89819.0 90728.0 69239.6 70597.2 72239.0 75435.6 80390.8 11024.8 11040.8 11082.3 11326.8 11357.2 50146.2 50853.0 52509.5 52560.0 53649.0 50448.0 53108.7 60745.9 69196.0 71734.3 12927.0 12990.3 13132.7 13157.2 13232.8 12946.2 14143.6 14376.3 14532.4 15123.6 13321.5 13446.1 13521.2 13584.3 14042.6 13219.7 13394.7 13960.4 14006.0 14052.3 10873.6 13186.3 13363.7 13499.6 14007.8 10908.6 10912.2 11089.7 11114.2 11404.4 8942.3 10422.3 10835.4 10842.0 10915.5 17:18:33 INFO - PROCESS | 1640 | -------- Summary: end -------- 17:18:33 INFO - PROCESS | 1640 | 17:18:34 INFO - TEST-INFO | 1640: exit 0 17:18:34 INFO - TEST-OK | dromaeo_css | took 1114731ms 17:18:34 INFO - TEST-START | kraken 17:18:34 INFO - operating with platform_type : mac_ 17:18:34 INFO - Initialising browser for kraken test... 17:18:34 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpzp4ylf/profile http://localhost:49240/getInfo.html) 17:18:37 INFO - PROCESS | 1651 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:18:37 INFO - PROCESS | 1651 | 2017-01-30 17:18:37.452 plugin-container[1652:9907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 17:18:37 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:37 INFO - PROCESS | 1651 | 2017-01-30 17:18:37.454 plugin-container[1652:9907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 17:18:37 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:37 INFO - PROCESS | 1651 | 2017-01-30 17:18:37.455 plugin-container[1652:9907] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:18:37 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 17:18:37 INFO - PROCESS | 1651 | colorDepth:24 17:18:37 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 17:18:37 INFO - PROCESS | 1651 | __metrics 17:18:37 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3284: TypeError: this.tabs is undefined 17:18:37 INFO - PROCESS | 1651 | 1485825517634 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpzp4ylf/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpzp4ylf/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpzp4ylf/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpzp4ylf/profile/extensions/talos-powers@TalosPowersService.js:55:9 17:18:38 INFO - TEST-INFO | 1651: exit 0 17:18:38 INFO - Browser initialized. 17:18:38 INFO - Running cycle 1/1 for kraken test... 17:18:38 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpzp4ylf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:40 INFO - PROCESS | 1653 | 2017-01-30 17:18:40.761 plugin-container[1654:10027] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 17:18:40 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:40 INFO - PROCESS | 1653 | 2017-01-30 17:18:40.761 plugin-container[1654:10027] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 17:18:40 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:40 INFO - PROCESS | 1653 | 2017-01-30 17:18:40.761 plugin-container[1654:10027] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:18:50 INFO - PROCESS | 1653 | RSS: Main: 233656320 17:18:50 INFO - PROCESS | 1653 | 17:18:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 17:18:54 INFO - PROCESS | 1653 | RSS: Main: 247480320 17:18:54 INFO - PROCESS | 1653 | 17:18:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 17:18:57 INFO - PROCESS | 1653 | RSS: Main: 255340544 17:18:57 INFO - PROCESS | 1653 | 17:19:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 17:19:01 INFO - PROCESS | 1653 | RSS: Main: 258068480 17:19:01 INFO - PROCESS | 1653 | 17:19:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 17:19:04 INFO - PROCESS | 1653 | RSS: Main: 260288512 17:19:04 INFO - PROCESS | 1653 | 17:19:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:19:06 INFO - PROCESS | 1653 | RSS: Main: 260640768 17:19:06 INFO - PROCESS | 1653 | 17:19:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 17:19:10 INFO - PROCESS | 1653 | RSS: Main: 265891840 17:19:10 INFO - PROCESS | 1653 | 17:19:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 17:19:14 INFO - PROCESS | 1653 | RSS: Main: 265310208 17:19:14 INFO - PROCESS | 1653 | 17:19:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 17:19:17 INFO - PROCESS | 1653 | RSS: Main: 265285632 17:19:17 INFO - PROCESS | 1653 | 17:19:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:19:20 INFO - PROCESS | 1653 | RSS: Main: 264667136 17:19:20 INFO - PROCESS | 1653 | 17:19:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:19:22 INFO - PROCESS | 1653 | RSS: Main: 264650752 17:19:22 INFO - PROCESS | 1653 | 17:19:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:19:25 INFO - PROCESS | 1653 | RSS: Main: 264728576 17:19:25 INFO - PROCESS | 1653 | 17:19:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:19:29 INFO - PROCESS | 1653 | RSS: Main: 265064448 17:19:29 INFO - PROCESS | 1653 | 17:19:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:19:33 INFO - PROCESS | 1653 | RSS: Main: 264835072 17:19:33 INFO - PROCESS | 1653 | 17:19:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 17:19:35 INFO - PROCESS | 1653 | RSS: Main: 266661888 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | __start_tp_report 17:19:35 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 17:19:35 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 17:19:35 INFO - PROCESS | 1653 | |i|pagename|runs| 17:19:35 INFO - PROCESS | 1653 | |0;ai-astar;105;116;120;115;119;124;168;173;121;118 17:19:35 INFO - PROCESS | 1653 | |1;audio-beat-detection;151;143;143;167;101;140;147;143;120;131 17:19:35 INFO - PROCESS | 1653 | |2;audio-dft;205;218;197;195;204;194;197;199;197;195 17:19:35 INFO - PROCESS | 1653 | |3;audio-fft;83;58;60;85;61;58;60;62;75;60 17:19:35 INFO - PROCESS | 1653 | |4;audio-oscillator;83;71;85;74;70;70;82;93;71;82 17:19:35 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;102;110;89;102;128;86;89;113;77;132 17:19:35 INFO - PROCESS | 1653 | |6;imaging-darkroom;94;95;95;95;97;95;95;97;96;96 17:19:35 INFO - PROCESS | 1653 | |7;imaging-desaturate;93;96;92;116;118;90;93;92;95;102 17:19:35 INFO - PROCESS | 1653 | |8;json-parse-financial;57;101;100;100;92;64;67;66;79;65 17:19:35 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;68;73;70;90;69;95;86;85;110;107 17:19:35 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;93;72;101;85;91;78;92;110;108;108 17:19:35 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;153;165;164;161;153;164;157;153;172;158 17:19:35 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;176;176;179;174;174;176;180;178;176;175 17:19:35 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;70;71;68;68;69;65;61;60;71;84 17:19:35 INFO - PROCESS | 1653 | __end_tp_report 17:19:35 INFO - PROCESS | 1653 | __start_cc_report 17:19:35 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,931 17:19:35 INFO - PROCESS | 1653 | __end_cc_report 17:19:35 INFO - PROCESS | 1653 | __startTimestamp1485825575096__endTimestamp 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | ------- Summary: start ------- 17:19:35 INFO - PROCESS | 1653 | Number of tests: 14 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:127.90 Median:120.50 stddev:23.04 (19.1%) stddev-sans-first:22.90 17:19:35 INFO - PROCESS | 1653 | Values: 105.0 116.0 120.0 115.0 119.0 124.0 168.0 173.0 121.0 118.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:143.00 stddev:17.98 (12.6%) stddev-sans-first:18.50 17:19:35 INFO - PROCESS | 1653 | Values: 151.0 143.0 143.0 167.0 101.0 140.0 147.0 143.0 120.0 131.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:200.10 Median:198.00 stddev:7.29 (3.7%) stddev-sans-first:7.52 17:19:35 INFO - PROCESS | 1653 | Values: 205.0 218.0 197.0 195.0 204.0 194.0 197.0 199.0 197.0 195.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:66.20 Median:61.50 stddev:10.58 (17.2%) stddev-sans-first:9.31 17:19:35 INFO - PROCESS | 1653 | Values: 83.0 58.0 60.0 85.0 61.0 58.0 60.0 62.0 75.0 60.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:82.00 stddev:7.98 (9.7%) stddev-sans-first:8.26 17:19:35 INFO - PROCESS | 1653 | Values: 83.0 71.0 85.0 74.0 70.0 70.0 82.0 93.0 71.0 82.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:104.50 stddev:18.18 (17.4%) stddev-sans-first:19.28 17:19:35 INFO - PROCESS | 1653 | Values: 102.0 110.0 89.0 102.0 128.0 86.0 89.0 113.0 77.0 132.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:95.50 stddev:0.97 (1.0%) stddev-sans-first:0.87 17:19:35 INFO - PROCESS | 1653 | Values: 94.0 95.0 95.0 95.0 97.0 95.0 95.0 97.0 96.0 96.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:98.70 Median:92.50 stddev:10.19 (11.0%) stddev-sans-first:10.59 17:19:35 INFO - PROCESS | 1653 | Values: 93.0 96.0 92.0 116.0 118.0 90.0 93.0 92.0 95.0 102.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:79.10 Median:65.50 stddev:17.49 (26.7%) stddev-sans-first:16.62 17:19:35 INFO - PROCESS | 1653 | Values: 57.0 101.0 100.0 100.0 92.0 64.0 67.0 66.0 79.0 65.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:85.30 Median:79.00 stddev:15.45 (19.6%) stddev-sans-first:15.06 17:19:35 INFO - PROCESS | 1653 | Values: 68.0 73.0 70.0 90.0 69.0 95.0 86.0 85.0 110.0 107.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:81.50 stddev:13.05 (16.0%) stddev-sans-first:13.83 17:19:35 INFO - PROCESS | 1653 | Values: 93.0 72.0 101.0 85.0 91.0 78.0 92.0 110.0 108.0 108.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:160.00 Median:162.50 stddev:6.34 (3.9%) stddev-sans-first:6.20 17:19:35 INFO - PROCESS | 1653 | Values: 153.0 165.0 164.0 161.0 153.0 164.0 157.0 153.0 172.0 158.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:176.00 stddev:2.01 (1.1%) stddev-sans-first:2.13 17:19:35 INFO - PROCESS | 1653 | Values: 176.0 176.0 179.0 174.0 174.0 176.0 180.0 178.0 176.0 175.0 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.50 stddev:6.63 (9.5%) stddev-sans-first:7.02 17:19:35 INFO - PROCESS | 1653 | Values: 70.0 71.0 68.0 68.0 69.0 65.0 61.0 60.0 71.0 84.0 17:19:35 INFO - PROCESS | 1653 | -------- Summary: end -------- 17:19:35 INFO - PROCESS | 1653 | 17:19:35 INFO - TEST-INFO | 1653: exit 0 17:19:36 INFO - TEST-OK | kraken | took 61622ms 17:19:36 INFO - SUITE-END | took 1176s 17:19:36 INFO - Completed test suite (00:19:36) 17:19:36 INFO - javascript benchmark 17:19:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7200.317035279215, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2810.1990932584213, 3092.2887735820746, 3251.170951407391, 3267.869285228591, 3278.6065672119335, 4496.955030449694, 4996.675216110959, 5438.265834112393, 5459.099248623972, 5473.425290344516, 4490.797914093857, 5456.781728730856, 5463.098588732864, 5550.2507161533185, 5585.553155747541, 3700.5559332879957, 4186.0, 4978.676386034915, 5185.611079169059, 5236.31927849379, 5037.924431133536, 5108.4636113208135, 5188.325517682696, 5192.273081768537, 5220.4779522047875, 4953.653244272902, 5021.14640511114, 5054.039732450822, 5095.898082038376, 5129.948700512984, 5035.320231768701, 5116.437191908887, 5128.512791284822, 5142.51146141116, 5158.871028224287, 3972.682185425188, 5061.0, 5065.898682026375, 5080.59355251579, 5107.770150343244, 5360.463953604647, 5423.7830486780485, 5448.536874365684, 5480.068388373986, 5539.612227144102, 6028.668423236721, 6037.215162028908, 6050.667213303266, 6075.2405949256345, 6092.933736596078, 6130.356312587159, 6133.049377346519, 6136.693165341715, 6140.293866205354, 6149.169862068608, 5563.443655634485, 5729.255196824428, 5732.598718089777, 5737.311522617271, 5744.770209191627, 5124.385073791094, 6104.847378815476, 6126.27339965311, 6129.325774164928, 6136.94800169746, 5964.821055368346, 6007.8197654070445, 6013.45878870895, 6031.698415079229, 6048.60684055535, 6086.9391306087255, 6117.96941015292, 6122.204113465221, 6129.172561704202, 6134.5092392608485, 5027.346444962204, 5816.8847085081725, 5933.228680271537, 6025.216721826135, 6056.303525094582, 6067.423594758491, 6091.360407157274, 6097.756089756449, 6119.44924956756, 6122.571420000646, 4892.094962431962, 4896.730679812645, 4898.191798353232, 4900.166971614763, 4903.656744027884, 4300.161468513672, 4307.612314891675, 4316.330968699794, 4343.109662519176, 4415.072834704683, 5630.155476678449, 5682.176084467812, 5710.65736055838, 5716.28546431696, 5722.198892155102, 6126.203593532812, 6143.078538219303, 6145.477634401037, 6154.169187159676, 6165.568410211242, 5515.4760297771645, 5627.409122042209, 5631.324241091013, 5634.887302253932, 5658.603897727202, 5218.9739051304505, 5264.31563896691, 5272.9736351318, 5314.388845282765, 5385.272988146628, 6169.660668663267, 6186.319504854462, 6191.0, 6198.783042593487, 6201.069839523927, 6123.510119190365, 6139.986902161093, 6140.078988151813, 6149.923763341487, 6155.261368635793, 6083.239595050619, 6099.0, 6110.892920173631, 6132.601380910227, 6155.076738489262, 5980.730867110903, 6031.004884194059, 6061.6666083365835, 6068.544859135494, 6094.695265236632, 6080.452759251689, 6110.572259941761, 6110.847228819315, 6114.205153330038, 6117.571769976059, 5751.281089863767, 5842.065269556851, 5862.7654893805475, 5908.822735317947, 5957.940420595912, 5981.641101533922, 5987.64074155552, 5991.67045812467, 5992.98119319708, 6022.7289771959495, 4734.313524538854, 5174.767135478837, 5644.266245388073, 5810.215620891295, 5816.82549523515, 4725.834595789131, 5366.838994830161, 5966.3735307793795, 5974.0, 5992.370801065826, 4249.46881639795, 5626.606137570331, 5876.1773351731645, 5887.499562537061, 5888.558358123072], "value": 5491.7679002071745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.5234137765703, 487.5295339996902, 488.367564004614, 490.31600916721163, 506.6985143839414, 33473.66526334739, 42848.35727464087, 42852.57147428529, 42870.35694464578, 42988.56091512679, 43423.131537369234, 43922.68231953039, 43948.780256098755, 43970.560294397044, 43977.7801110994, 8534.87197692035, 9321.440713557191, 9525.666601668943, 9539.18916892065, 9555.044495550459, 8612.655493780241, 11352.545898164064, 11593.130515211351, 11636.243644163102, 11825.881741182564, 11616.012638925702, 11656.825147622792, 11657.650270491857, 11687.883121168807, 11716.589919352824, 11634.592789252378, 11658.6502404928, 11687.415629218505, 11688.181827272094, 11710.648680539598, 11432.028277596415, 11451.599194028253, 11456.0262377698, 11530.654080377602, 11545.01867341277, 41119.97200069992, 41421.37867931984, 41488.792556037326, 41509.0, 41573.96065098367, 4011.0, 4021.678265738734, 4025.6175663311938, 4034.3141665916864, 4039.272930872442, 24034.245455491193, 30715.0, 30779.230519236975, 31063.378732425226, 31920.840395797873, 3189.282411457427, 3194.2014496375905, 3196.952045719316, 3202.711755941976, 3308.751843611739, 474742.0, 477150.0, 483448.0, 634960.8251958757, 638720.0, 56867.86264274691, 57513.84972300614, 57632.71183644055, 57706.0, 57774.0, 61183.0, 61408.692956534935, 61476.0, 61707.6914615424, 61930.69034654888, 14715.632109197142, 14717.043392179474, 14854.0, 14877.33052012662, 15020.849791502163, 10430.530626121843, 10647.733806654896, 11103.888961110286, 11146.386948717898, 11198.272112312674, 17902.731459028124, 18166.455006349832, 18299.634007320044, 18304.359347422775, 18342.266309347742, 6605.70274337656, 6874.072000279998, 7572.356349710226, 7753.0, 7912.525248485109, 756.4402342266683, 827.5283088639538, 828.6726742936506, 831.9750407487785, 854.5556310718391, 28798.856005719837, 30586.84706576453, 30712.078637640905, 30745.846270768503, 31459.213519662193, 1042.7549525861416, 1048.2452634103433, 1048.7954848804563, 1076.7133275735578, 1164.4876254448016, 15880.047197168207, 16010.679786404207, 16079.839201608069, 16308.26612802403, 16371.590710232102, 608934.0, 610562.0, 612558.0, 613575.0, 615094.0, 59905.0, 60633.0, 60661.0, 60792.08811867826, 60900.086498702556, 1136.909047276216, 1196.7905616517075, 1408.1480704173828, 1420.2969530082676, 1430.2634143415942, 24349.477307118257, 26157.869210653826, 27711.722882770915, 29704.514774261632, 30229.0, 51421.97156056933, 51569.0, 51785.482145178066, 51845.48154518406, 52033.7398313006, 472380.0, 472760.0, 474037.0, 475037.0, 477380.0, 22426.66360004634, 25353.87323063373, 25915.740842592088, 26029.349266267738, 26947.326316841452, 43222.78388608037, 43510.78244608884, 44490.55509444864, 44775.880602985184, 44833.327500086874, 36334.0, 36833.169867119264, 37359.0, 37378.43932341071, 37449.0, 35899.82050089733, 36060.0, 36757.44863826991, 36947.44578831266, 37157.628423716495, 35956.4606530897, 36440.63559364479, 36892.63107368892, 38188.809055954545, 38222.61777382302, 31442.842785785924, 33022.33955320928, 33060.66939330672, 33101.0, 33194.66805331916, 24114.87942560346, 24189.911453984965, 24524.50950981006, 24529.75470245275, 25035.87482062578], "value": 19920.44722682136, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.13094127448977, 394.38869751884573, 401.7468994533443, 405.8518640696146, 426.24980035138145, 14746.188959607243, 16493.83506164938, 19609.31367402141, 21020.789792102074, 21038.474038149034, 21055.0, 21272.255471058517, 21582.676259856074, 21683.349499515, 21915.890420547876, 5252.947470525303, 5675.063614503602, 5699.515541179005, 5739.827805165852, 5754.597178197507, 5579.191017302509, 5615.382307946143, 5728.0, 5733.197352370671, 5745.166950792153, 5864.648121112726, 5987.401259873999, 5992.131140984555, 6000.579959402844, 6005.519558435337, 5932.050871860503, 5967.313758917715, 5997.37027612101, 6440.935590644127, 6521.380468855451, 5787.681677507735, 5796.420357964212, 5800.796972105956, 5804.622699524517, 5812.157237200582, 13014.609561713163, 13221.471141154343, 13374.930005599528, 13413.865861341359, 13421.731565368638, 2106.8735875847347, 2110.472381904524, 2134.957300853974, 2164.7618761936174, 2931.457680329146, 9492.762680932963, 9882.9505852471, 10411.94794026025, 10420.166386689047, 10614.416207108607, 1847.5935294235246, 1852.4998250472297, 1867.8692491525599, 1869.5699989002605, 1892.4133518609276, 140870.0, 142958.2852085733, 144299.99999999895, 146327.0, 147681.0, 11719.663724422018, 11730.0, 11912.927836494757, 12028.45871935765, 12289.938550307192, 15226.390944362323, 15228.46700365504, 15510.922445387927, 15533.301001454962, 15548.0, 8115.59422028908, 8371.623276952552, 8415.116412776693, 8486.872696909551, 8508.532030738244, 7936.682532698743, 7986.361091112697, 8052.879206811903, 8174.67301307941, 8175.264226219669, 4673.672842901033, 4690.349952277021, 4734.431868175772, 4744.667873248908, 4758.666893317504, 1988.2146552112124, 2036.2872994451823, 2051.98974005129, 2052.9794702053086, 2054.250198677472, 846.2848892685671, 849.4648371525899, 853.3685073046025, 867.5402036920442, 882.8587426011808, 19962.90018549927, 20508.38474845757, 20537.48656283605, 20557.383278501668, 20738.75567465957, 878.0867897386646, 885.751989442957, 899.6536333511646, 907.2107266678032, 911.2163539356148, 10312.896871031193, 10353.0, 10659.89340106589, 10692.679219623424, 10712.250142490107, 673676.0, 724733.0, 733855.0, 746768.0, 756181.0, 120044.0, 151347.24326378517, 156761.0, 160656.0, 164922.9999999976, 1000.4547521600745, 1018.6587493189717, 1045.487711021594, 1046.2362475393006, 1049.4070849969742, 15904.761428578811, 16610.833891661412, 19820.549348928584, 20027.198912043354, 20056.59886802285, 51918.0, 56029.599260018826, 61974.69012654908, 63312.36687633249, 63907.680461597396, 93308.0, 94558.0, 94604.52697736467, 94810.52594736982, 94850.82939157939, 7058.71765129389, 7555.697772089275, 7617.238276172338, 8118.512889226666, 8626.525541095049, 9345.0, 12373.75252494963, 12882.0, 13350.19898806036, 13393.0, 10895.836562451412, 11215.102791776475, 11517.0, 11551.075913926697, 11745.177837551631, 9859.65491207833, 11009.779804403706, 11148.0, 11223.4388280584, 11296.209265351346, 11372.772544549229, 11567.132465065322, 11759.470823812779, 11766.58816941403, 11843.940780296043, 7383.0, 7571.205023472677, 7702.499337543042, 7712.5758083305955, 7811.570363630055, 8924.286057115285, 10353.327033742782, 10642.73393165177, 10799.190060745632, 10970.506327215455], "value": 9606.798736360253, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.7755803955286, 1395.0862185268652, 1412.6962703018849, 1413.9434422623096, 1416.6458385403648, 7504.324610785028, 7758.573278469696, 8038.276555110039, 8480.872786908194, 8488.108748581395, 7932.325752311061, 8190.467619604721, 8253.876191857127, 8326.209010144043, 8363.540005299707, 1906.65680177568, 2084.5101401170714, 2238.921637742679, 2287.7025986621716, 2297.0926484038796, 1847.436531857783, 1946.980530194694, 2259.48031952651, 2304.6543018547186, 2348.283773449097, 2289.713785776778, 2311.872846993415, 2316.3745788637057, 2317.640765681322, 2318.234982455794, 2274.613315736329, 2279.4415368234804, 2286.5541219462043, 2289.645105008726, 2295.598270302699, 2203.294945617403, 2260.706108205939, 2289.3360925331635, 2307.3654744945106, 2308.6306191009357, 6983.650817459157, 7724.30481256684, 8213.89112469815, 8439.240468357815, 8475.533845638487, 5073.289739436482, 5241.68549887008, 5244.842654720364, 5246.737663116867, 5258.474152584749, 3297.505374193866, 3486.267883744417, 3508.0528257370624, 3551.271989242199, 3569.036360182736, 1099.5711672447671, 1175.1069187417625, 1211.9272843629321, 1265.8544267409275, 1276.757416090949, 8824.691135810215, 9569.90430095697, 9975.40393537038, 10683.83974240387, 10821.242513024092, 4380.969508052599, 4402.119576084796, 4495.572920572606, 4508.391367165391, 4518.367428559938, 8545.529995850164, 8611.741647750578, 8645.610947507377, 8660.696875609323, 8667.783305417415, 4584.908301833944, 4930.2851086592245, 5103.948960510347, 5105.285260063594, 5105.566026887756, 4598.839040633562, 4604.37840891482, 4735.171345014677, 4788.736619485892, 4838.225883858636, 3569.1077230692326, 3630.5098811660614, 3671.127724860287, 3693.3351996640326, 3725.720570957189, 921.3734660430837, 1082.9079528240031, 1097.7365432296192, 1162.3839365136491, 1166.7549814539043, 780.6487080813656, 858.9613467393857, 883.1212943121635, 885.2386947225381, 921.7879887625884, 5312.0, 5669.121286200645, 5684.63049901755, 5731.570132240098, 5899.292084949748, 970.752458123174, 970.84466485362, 976.4190306767462, 996.14829320931, 1026.1431704526653, 3165.920851978719, 3523.947140792839, 3536.2927414516685, 3715.108373990223, 3731.533558305212, 9527.809443811086, 9760.902390976142, 9850.310478266452, 9862.309638325392, 9968.651097211563, 7728.729494467667, 9810.65662701802, 9841.55712992917, 9845.310828242098, 9866.161376282953, 7330.193678695339, 7625.847483050419, 7658.578778167142, 7678.963339949147, 7784.2994130527395, 4802.903941921072, 5530.723463826712, 5681.9147712783515, 5755.424457554211, 5790.276215473066, 8809.691660791841, 8945.865812012955, 8955.59699813497, 9068.637254509746, 9112.863307050533, 8853.29173666118, 9137.908620913706, 9167.908320916973, 9244.168024877525, 9292.674756383494, 6511.34886511345, 6966.442684585119, 7270.272972702687, 7270.418366530771, 7288.635568221462, 6938.548994315555, 7782.688692452239, 7806.219378062148, 7854.136045035156, 7877.842443151219, 6385.8722825544155, 7087.22040575526, 7117.964410177916, 7138.321859423347, 7157.355837974399, 6882.0, 6928.376446119873, 6960.47796415281, 6980.6509674515055, 7003.964980175067, 6276.968615156895, 6317.873642527032, 6380.0, 6396.616203027833, 6405.103285540122, 4818.734969576707, 4905.6075513958085, 4929.383827021622, 4996.650234483696, 5100.311457953128, 4896.11869863428, 4921.4832442593015, 4973.776180071978, 5005.224190250517, 5066.0], "value": 4311.964452273065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.70831917827377, 358.48199351936455, 369.4476757247914, 370.449881925341, 376.42031271841364, 4916.58209052231, 5076.46697096805, 5100.719460429675, 5180.378354597449, 5339.7330133493315, 5076.314697515834, 5119.846404607859, 5274.287971123907, 5327.7336133193285, 5330.626856120063, 4176.6843444314945, 4209.810558524866, 4227.471566054243, 4234.618884300412, 4250.213710463574, 3389.440742277521, 3533.522974398462, 3890.9416358754643, 4211.305134652794, 4234.1320029394055, 3593.5508061492314, 3975.7415767975135, 4104.384342348657, 4169.374593810938, 4192.769397683126, 4163.0, 4171.541130475645, 4176.60322269384, 4178.811953462102, 4181.344846022782, 3128.4838001729686, 3167.7307428868576, 3681.9815900920594, 3711.3876210425246, 3735.4210097434943, 4313.698041137121, 5175.774417000688, 5248.685078895279, 5271.841844744664, 5309.283246761676, 4463.33050042493, 4488.214562451558, 4654.604358629521, 4877.317175595419, 4904.528641407564, 5433.918491222635, 5462.617616766828, 5738.684372359518, 5745.569082318842, 5803.533498263221, 3865.873769426804, 3875.5155605549307, 4071.5724848890736, 4087.3255912774653, 4148.149629325981, 5387.087583907695, 5640.379558248589, 5969.343372229051, 6002.279726432856, 6041.667708276043, 4185.953511622094, 4207.221663992172, 4210.410542524049, 4214.7892605370225, 4254.914901701949, 3658.5426821647293, 3929.1945151243926, 3993.3610622300876, 4000.7599544027453, 4271.0492794165275, 4633.7683115844075, 4648.48866624671, 4660.953390466119, 4669.929951050737, 4693.976530117396, 5186.325777648882, 5390.248169344978, 5657.98156331856, 5750.913736293959, 5765.250517432707, 1069.4706120470262, 1145.68493664243, 1162.9475324831042, 1181.149572307937, 1273.446050967832, 842.3682238321259, 858.296197118357, 872.284726524244, 876.6099085906837, 879.2921698033017, 4668.758995682092, 4968.378952630921, 5006.047641419849, 5067.8986420271385, 5077.81653888824, 4487.955120448819, 4856.368672072678, 5170.1727723564045, 5532.0, 5560.805371811967, 4171.452694410916, 4314.590113939171, 4338.761368124783, 4684.648651351163, 5052.595792336604, 2543.5167318209483, 2829.5189817731034, 2861.7567506762157, 2883.1206482023185, 2944.5413080077947, 4969.552740253394, 5383.892322153613, 5622.297212848394, 5663.292088488939, 5699.914501282484, 4665.95334046662, 5323.574114070865, 5348.812791552355, 5740.971295143581, 5980.833737421248, 4982.725950072709, 5203.531682148549, 6010.579259451796, 6099.298580663103, 6102.176206212193, 5020.393474088257, 5139.974300128625, 5803.390643982321, 5858.7070646466655, 5988.580799344004, 5595.720213989365, 5658.886822263449, 5728.226689396878, 5777.595568310178, 5803.709814509342, 5119.0, 5539.778408863762, 6030.39696030411, 6043.576949613661, 6079.544034197364, 3384.983075084609, 3399.6600339965808, 3916.0, 3930.6462418382484, 3934.075492259374, 4854.563089321978, 5482.0, 5551.250581171652, 5739.278216535106, 5887.499562537232, 4920.92618610714, 5301.469853014668, 5628.943710562842, 5673.34756502991, 5687.857803554944, 5259.42146363892, 5667.744951477276, 5688.497061293017, 5699.800506982236, 5703.20155178267, 5203.11547036997, 5457.481539253765, 5526.253955715927, 5599.636023658449, 5614.66991198648, 5014.52362025607, 5650.943490565206, 5660.943390566042, 5673.602847800779, 5691.146328050825, 4701.553352431513, 5285.365756109291, 5543.667379957215, 5576.386597474356, 5603.327600687943], "value": 4111.342105370076, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.8022773395716, 692.6052150274343, 747.3983443328119, 792.5799326357031, 855.2260204514916, 14691.191984440851, 15009.024413413148, 15062.322195501201, 15943.202839858004, 15965.521034368972, 14331.0, 14608.926955365208, 14623.561293161221, 14651.120932744043, 15546.689066218669, 854.3848429131032, 894.3650008493945, 895.8253140637578, 897.6409436225497, 908.4276905549494, 685.5441131647449, 705.9929400705979, 877.5919197573127, 878.6529320918265, 880.55972013993, 686.9798349451061, 903.5723556780305, 904.656230632362, 905.9048799876017, 925.4956048953337, 919.5999740113073, 923.64901337492, 924.4222361024359, 924.6255266617031, 925.1165137293901, 686.3788271614197, 687.525607330945, 688.7589343729704, 689.9206591242021, 690.4510913823523, 12770.361481925867, 14564.85435145656, 14590.051646642934, 15784.210789460481, 15916.681666366723, 13573.253471059088, 13592.660183495393, 13796.0, 13800.861991380058, 13889.236092014935, 12780.552680656132, 13071.41178646963, 13102.541411050568, 13142.671433214151, 13222.66943326415, 4371.825126994916, 4376.23415902219, 4421.182081314967, 4445.399871017404, 4588.541145885418, 108831.45584272029, 112604.0, 112813.0, 112924.0, 114167.42916285366, 10693.358398496115, 10740.462976851282, 10809.945950270197, 10864.837027444595, 10955.068819150461, 21343.35969920905, 21443.035063422183, 21568.24511142134, 21645.67531487029, 21687.34937951864, 7427.591482468408, 7446.627668616547, 7486.5508069516, 7496.137944136494, 7634.236576342321, 4072.959270407258, 4084.6323830855367, 4112.992316882383, 4158.771267580251, 4160.958390416057, 2970.3168271297723, 3385.9153521161675, 3493.4410494320787, 3493.458513930345, 3509.6314886937016, 628.6793735195083, 635.6980434293673, 638.1958731997623, 639.4756299834091, 649.1042361541043, 602.2832828933642, 607.5109536822812, 627.8493161641173, 630.8296759874808, 635.7393468677819, 9354.391964522285, 9754.561044753003, 9791.314607977372, 9916.851247231298, 10037.297389182688, 611.6421196288334, 616.1927874484351, 647.5564238496645, 647.6761619190405, 666.7664820082808, 4711.053393936353, 4830.975845120752, 4860.9513904861205, 4894.342534285771, 4903.705777653352, 85797.57101214578, 87272.69090963641, 96326.51836740771, 102905.0, 105866.0, 74999.62500187464, 83542.16457835466, 87999.56000220086, 89819.0, 90728.0, 69239.61520769556, 70597.23506912368, 72239.0, 75435.62282188554, 80390.79413808798, 11024.834627480755, 11040.779184416428, 11082.279651822928, 11326.83009754871, 11357.204995650225, 50146.247806282205, 50853.0, 52509.474905251984, 52560.0, 53649.0, 50447.991040178254, 53108.734456327475, 60745.87389441348, 69196.0, 71734.28265717484, 12927.0, 12990.285534295705, 13132.671683207996, 13157.160786501156, 13232.801507977196, 12946.158499697489, 14143.585641435775, 14376.28118594087, 14532.418703251751, 15123.621909452351, 13321.533746318832, 13446.12378369745, 13521.18872867631, 13584.252866092065, 14042.578722638336, 13219.735605288033, 13394.732105357643, 13960.441582336593, 14006.0, 14052.297385130907, 10873.565057397616, 13186.274754888573, 13363.732725345633, 13499.595012149652, 14007.789883151965, 10908.61819836302, 10912.181586380895, 11089.667309980714, 11114.166437517057, 11404.372759498307, 8942.329325300498, 10422.322549034288, 10835.404052776857, 10842.024217820433, 10915.508802104083], "value": 7478.4633873721, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1571.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 120.0, 115.0, 119.0, 124.0, 168.0, 173.0, 121.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 143.0, 143.0, 167.0, 101.0, 140.0, 147.0, 143.0, 120.0, 131.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 218.0, 197.0, 195.0, 204.0, 194.0, 197.0, 199.0, 197.0, 195.0], "value": 200.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 60.0, 85.0, 61.0, 58.0, 60.0, 62.0, 75.0, 60.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 85.0, 74.0, 70.0, 70.0, 82.0, 93.0, 71.0, 82.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 89.0, 102.0, 128.0, 86.0, 89.0, 113.0, 77.0, 132.0], "value": 102.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0, 97.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 92.0, 116.0, 118.0, 90.0, 93.0, 92.0, 95.0, 102.0], "value": 98.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 101.0, 100.0, 100.0, 92.0, 64.0, 67.0, 66.0, 79.0, 65.0], "value": 79.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 70.0, 90.0, 69.0, 95.0, 86.0, 85.0, 110.0, 107.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 72.0, 101.0, 85.0, 91.0, 78.0, 92.0, 110.0, 108.0, 108.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 165.0, 164.0, 161.0, 153.0, 164.0, 157.0, 153.0, 172.0, 158.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 179.0, 174.0, 174.0, 176.0, 180.0, 178.0, 176.0, 175.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 68.0, 68.0, 69.0, 65.0, 61.0, 60.0, 71.0, 84.0], "value": 68.7, "unit": "score"}]}]} 17:19:36 INFO - Return code: 0 17:19:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:19:36 ERROR - # TBPL SUCCESS # 17:19:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:19:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:19:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:19:36 INFO - 2017-01-30 17:19:36.379 screenresolution[1658:10415] starting screenresolution argv=screenresolution get 17:19:36 INFO - 2017-01-30 17:19:36.381 screenresolution[1658:10415] Display 0: 1600x1200x32@60 17:19:36 INFO - 2017-01-30 17:19:36.388 screenresolution[1659:10418] starting screenresolution argv=screenresolution list 17:19:36 INFO - Available Modes on Display 0 17:19:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:19:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:19:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:19:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:19:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:19:36 INFO - Intel Iris: 17:19:36 INFO - Chipset Model: Intel Iris 17:19:36 INFO - Type: GPU 17:19:36 INFO - Bus: Built-In 17:19:36 INFO - VRAM (Dynamic, Max): 1536 MB 17:19:36 INFO - Vendor: Intel (0x8086) 17:19:36 INFO - Device ID: 0x0a2e 17:19:36 INFO - Revision ID: 0x0009 17:19:36 INFO - Displays: 17:19:36 INFO - Display: 17:19:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:19:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:19:36 INFO - Display Serial Number: 1600x1200 60 17:19:36 INFO - Main Display: Yes 17:19:36 INFO - Mirror: Off 17:19:36 INFO - Online: Yes 17:19:36 INFO - Rotation: Supported 17:19:36 INFO - Return code: 0 17:19:36 INFO - Running post-action listener: _resource_record_post_action 17:19:36 INFO - [mozharness: 2017-01-31 01:19:36.625329Z] Finished run-tests step (success) 17:19:36 INFO - Running post-run listener: _resource_record_post_run 17:19:36 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 38283776; Write bytes: 557136384; Read time: 339; Write time: 1707 17:19:36 INFO - TinderboxPrint: CPU usage
54.5% 17:19:36 INFO - TinderboxPrint: I/O read bytes / time
38,283,776 / 339 17:19:36 INFO - TinderboxPrint: I/O write bytes / time
557,136,384 / 1,707 17:19:36 INFO - TinderboxPrint: CPU idle
2,185.1 (45.5%) 17:19:36 INFO - TinderboxPrint: CPU system
965.7 (20.1%) 17:19:36 INFO - TinderboxPrint: CPU user
1,646.9 (34.3%) 17:19:36 INFO - TinderboxPrint: Swap in / out
290,787,328 / 0 17:19:36 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 242414080; Write bytes: 237119488; Read time: 18039; Write time: 512 17:19:36 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36313600; Write bytes: 300737024; Read time: 322; Write time: 1146 17:19:37 INFO - Running post-run listener: _upload_blobber_files 17:19:37 INFO - Blob upload gear active. 17:19:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:19:37 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 17:19:37 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'] 17:19:37 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 17:19:37 INFO - (blobuploader) - INFO - Open directory for files ... 17:19:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:19:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:19:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:19:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:38 INFO - (blobuploader) - INFO - Done attempting. 17:19:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:19:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:19:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:19:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:39 INFO - (blobuploader) - INFO - Done attempting. 17:19:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:19:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:19:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:19:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:40 INFO - (blobuploader) - INFO - Done attempting. 17:19:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:19:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:19:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:19:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:42 INFO - (blobuploader) - INFO - Done attempting. 17:19:42 INFO - (blobuploader) - INFO - Iteration through files over. 17:19:42 INFO - Return code: 0 17:19:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:19:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:19:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe10b3b3f82ad45a4e2285cee8cf89548fc5aea1e5739320407aa4ce1ddfc956a678d1202135532702144ebda181fd16f7d7bfc736cbffaa66b901dd03e870", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faeed51cf7db03c5b804ab656d465a7eaf8c74d658b60ee5b412641db70998d7e34d227ce26098da543365b9f210f2ee7ee9b08ab78824130a404f03c6cff99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e55f19ebe150d9756821d938f40b9f0884ce089b8ccb72cf09119c0cffa200679383211885851458b333213ba2e78a3a5f999f965a3620f4a6ebc083b0be9324", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53247dc210b36b6d3f929a706c128e2396d5667291929b87be881c2e90719331321d6ca9fc68bc6e2f87366fd204a08f0ce9c59c9bdad3c31aba981b6e6ac92d"} 17:19:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:19:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:19:42 INFO - Contents: 17:19:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe10b3b3f82ad45a4e2285cee8cf89548fc5aea1e5739320407aa4ce1ddfc956a678d1202135532702144ebda181fd16f7d7bfc736cbffaa66b901dd03e870", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faeed51cf7db03c5b804ab656d465a7eaf8c74d658b60ee5b412641db70998d7e34d227ce26098da543365b9f210f2ee7ee9b08ab78824130a404f03c6cff99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e55f19ebe150d9756821d938f40b9f0884ce089b8ccb72cf09119c0cffa200679383211885851458b333213ba2e78a3a5f999f965a3620f4a6ebc083b0be9324", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53247dc210b36b6d3f929a706c128e2396d5667291929b87be881c2e90719331321d6ca9fc68bc6e2f87366fd204a08f0ce9c59c9bdad3c31aba981b6e6ac92d"} 17:19:42 INFO - Running post-run listener: copy_logs_to_upload_dir 17:19:42 INFO - Copying logs to upload dir... 17:19:42 INFO - mkdir: /builds/slave/test/build/upload/logs 17:19:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.590196 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-30 17:19:42.807177) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 17:19:42.814621) ========= 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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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/9fbe10b3b3f82ad45a4e2285cee8cf89548fc5aea1e5739320407aa4ce1ddfc956a678d1202135532702144ebda181fd16f7d7bfc736cbffaa66b901dd03e870", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faeed51cf7db03c5b804ab656d465a7eaf8c74d658b60ee5b412641db70998d7e34d227ce26098da543365b9f210f2ee7ee9b08ab78824130a404f03c6cff99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e55f19ebe150d9756821d938f40b9f0884ce089b8ccb72cf09119c0cffa200679383211885851458b333213ba2e78a3a5f999f965a3620f4a6ebc083b0be9324", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53247dc210b36b6d3f929a706c128e2396d5667291929b87be881c2e90719331321d6ca9fc68bc6e2f87366fd204a08f0ce9c59c9bdad3c31aba981b6e6ac92d"} build_url:https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011137 build_url: 'https://queue.taskcluster.net/v1/task/bhL3UM43T0ibQwOIb39smg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fbe10b3b3f82ad45a4e2285cee8cf89548fc5aea1e5739320407aa4ce1ddfc956a678d1202135532702144ebda181fd16f7d7bfc736cbffaa66b901dd03e870", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3faeed51cf7db03c5b804ab656d465a7eaf8c74d658b60ee5b412641db70998d7e34d227ce26098da543365b9f210f2ee7ee9b08ab78824130a404f03c6cff99", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e55f19ebe150d9756821d938f40b9f0884ce089b8ccb72cf09119c0cffa200679383211885851458b333213ba2e78a3a5f999f965a3620f4a6ebc083b0be9324", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53247dc210b36b6d3f929a706c128e2396d5667291929b87be881c2e90719331321d6ca9fc68bc6e2f87366fd204a08f0ce9c59c9bdad3c31aba981b6e6ac92d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 17:19:42.849110) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 17:19:42.852445) ========= 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.t7RlffsAaJ/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.cwIQK2XG6t/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.005171 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 17:19:42.900867) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 17:19:42.901163) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 17:19:42.904493) ========= ========= Total master_lag: 0.19 =========