builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0221 starttime: 1483811196.91 results: success (0) buildid: 20170107082057 builduid: 3ec682774f674096af267a9aa58ece30 revision: 2d62b03b833b7e142c530dbcaf4424e357769170 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:36.907063) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:36.907604) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:36.908324) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:36.971959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:36.972318) ========= 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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-07 09:46:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 65.3M=0s 2017-01-07 09:46:37 (65.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122834 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:37.129978) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:37.130340) ========= 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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.019414 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:37.195716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 09:46:37.196182) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2d62b03b833b7e142c530dbcaf4424e357769170 --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 2d62b03b833b7e142c530dbcaf4424e357769170 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4hLRRfxhyk/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.C8AoWYsPMj/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-07 09:46:37,276 truncating revision to first 12 chars 2017-01-07 09:46:37,277 Setting DEBUG logging. 2017-01-07 09:46:37,277 attempt 1/10 2017-01-07 09:46:37,277 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2d62b03b833b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 09:46:38,070 unpacking tar archive at: mozilla-central-2d62b03b833b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.074318 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 09:46:38.295951) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:38.296362) ========= script_repo_revision: 2d62b03b833b7e142c530dbcaf4424e357769170 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:38.296921) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:38.297270) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 09:46:38.317461) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-07 09:46:38.317846) ========= /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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:46:38 INFO - MultiFileLogger online at 20170107 09:46:38 in /builds/slave/test 09:46:38 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 09:46:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:46:38 INFO - {'append_to_log': False, 09:46:38 INFO - 'base_work_dir': '/builds/slave/test', 09:46:38 INFO - 'blob_upload_branch': 'Firefox', 09:46:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:46:38 INFO - 'branch': 'Firefox', 09:46:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:46:38 INFO - 'config_files': ('talos/mac_config.py',), 09:46:38 INFO - 'default_actions': ('clobber', 09:46:38 INFO - 'read-buildbot-config', 09:46:38 INFO - 'download-and-extract', 09:46:38 INFO - 'populate-webroot', 09:46:38 INFO - 'create-virtualenv', 09:46:38 INFO - 'install', 09:46:38 INFO - 'run-tests'), 09:46:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:46:38 INFO - 'download_minidump_stackwalk': True, 09:46:38 INFO - 'download_symbols': 'ondemand', 09:46:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:46:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:46:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:46:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:46:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:46:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:46:38 INFO - 'installer_path': 'installer.exe', 09:46:38 INFO - 'log_level': 'info', 09:46:38 INFO - 'log_name': 'talos', 09:46:38 INFO - 'log_to_console': True, 09:46:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:46:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:46:38 INFO - 'opt_config_files': (), 09:46:38 INFO - 'pip_index': False, 09:46:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:38 INFO - 'cmd': ('bash', 09:46:38 INFO - '-c', 09:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:38 INFO - 'enabled': True, 09:46:38 INFO - 'halt_on_failure': False, 09:46:38 INFO - 'name': 'check_screen_resolution'},), 09:46:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:38 INFO - 'cmd': ('bash', 09:46:38 INFO - '-c', 09:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:38 INFO - 'enabled': True, 09:46:38 INFO - 'halt_on_failure': False, 09:46:38 INFO - 'name': 'check_screen_resolution'},), 09:46:38 INFO - 'run_cmd_checks_enabled': True, 09:46:38 INFO - 'sps_profile': False, 09:46:38 INFO - 'sps_profile_interval': 0, 09:46:38 INFO - 'suite': 'dromaeojs-e10s', 09:46:38 INFO - 'system_bits': '32', 09:46:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:46:38 INFO - 'title': 't-yosemite-r7-0221', 09:46:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:46:38 INFO - 'use_talos_json': True, 09:46:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:46:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:46:38 INFO - 'work_dir': 'build'} 09:46:38 INFO - [mozharness: 2017-01-07 17:46:38.517477Z] Running clobber step. 09:46:38 INFO - Running pre-action listener: _resource_record_pre_action 09:46:38 INFO - Running main action method: clobber 09:46:38 INFO - rmtree: /builds/slave/test/build 09:46:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:46:40 INFO - Running post-action listener: _resource_record_post_action 09:46:40 INFO - [mozharness: 2017-01-07 17:46:40.036985Z] Finished clobber step (success) 09:46:40 INFO - [mozharness: 2017-01-07 17:46:40.037124Z] Running read-buildbot-config step. 09:46:40 INFO - Running pre-action listener: _resource_record_pre_action 09:46:40 INFO - Running main action method: read_buildbot_config 09:46:40 INFO - Using buildbot properties: 09:46:40 INFO - { 09:46:40 INFO - "project": "", 09:46:40 INFO - "product": "firefox", 09:46:40 INFO - "script_repo_revision": "production", 09:46:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:46:40 INFO - "repository": "", 09:46:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:46:40 INFO - "buildid": "20170107082057", 09:46:40 INFO - "pgo_build": "False", 09:46:40 INFO - "basedir": "/builds/slave/test", 09:46:40 INFO - "buildnumber": 38, 09:46:40 INFO - "slavename": "t-yosemite-r7-0221", 09:46:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:46:40 INFO - "platform": "yosemite_r7", 09:46:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:46:40 INFO - "branch": "mozilla-central", 09:46:40 INFO - "repo_path": "mozilla-central", 09:46:40 INFO - "revision": "2d62b03b833b7e142c530dbcaf4424e357769170", 09:46:40 INFO - "stage_platform": "macosx64", 09:46:40 INFO - "builduid": "3ec682774f674096af267a9aa58ece30", 09:46:40 INFO - "slavebuilddir": "test" 09:46:40 INFO - } 09:46:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:46:40 INFO - Running post-action listener: _resource_record_post_action 09:46:40 INFO - [mozharness: 2017-01-07 17:46:40.040616Z] Finished read-buildbot-config step (success) 09:46:40 INFO - [mozharness: 2017-01-07 17:46:40.040709Z] Running download-and-extract step. 09:46:40 INFO - Running pre-action listener: _resource_record_pre_action 09:46:40 INFO - Running main action method: download_and_extract 09:46:40 INFO - mkdir: /builds/slave/test/build/tests 09:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:40 INFO - trying https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:46:40 INFO - Downloading https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:46:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:46:41 INFO - Downloaded 1199 bytes. 09:46:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:46:41 INFO - Using the following test package requirements: 09:46:41 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:46:41 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:46:41 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:46:41 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'jsshell-mac.zip'], 09:46:41 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:46:41 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:46:41 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:46:41 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:46:41 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:46:41 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:46:41 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:46:41 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:46:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:46:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:46:41 INFO - Fetch https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:46:42 INFO - Expected file size: 16301544 09:46:42 INFO - Obtained file size: 16301544 09:46:49 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:46:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:46:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:46:49 INFO - Fetch https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:46:50 INFO - Expected file size: 16301544 09:46:50 INFO - Obtained file size: 16301544 09:46:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:46:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:46:56 INFO - Fetch https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:46:57 INFO - Expected file size: 14403537 09:46:57 INFO - Obtained file size: 14403537 09:46:57 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')]} 09:46:57 INFO - trying https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:46:57 INFO - Downloading https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:46:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:46:59 INFO - Downloaded 69658509 bytes. 09:46:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:46:59 INFO - mkdir: /builds/slave/test/properties 09:46:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:46:59 INFO - Writing to file /builds/slave/test/properties/build_url 09:46:59 INFO - Contents: 09:46:59 INFO - build_url:https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:47:00 INFO - Running post-action listener: _resource_record_post_action 09:47:00 INFO - Running post-action listener: set_extra_try_arguments 09:47:00 INFO - [mozharness: 2017-01-07 17:47:00.660256Z] Finished download-and-extract step (success) 09:47:00 INFO - [mozharness: 2017-01-07 17:47:00.660379Z] Running populate-webroot step. 09:47:00 INFO - Running pre-action listener: _resource_record_pre_action 09:47:00 INFO - Running main action method: populate_webroot 09:47:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:47:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:47:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:47:00 INFO - '--tppagecycles', 09:47:00 INFO - '7'], 09:47:00 INFO - 'tests': ['tsvgm']}}, 09:47:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:47:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:47:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:47:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:47:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:47:00 INFO - 'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:47:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:47:00 INFO - 'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['damp', 'tps']}, 09:47:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:47:00 INFO - 'tests': ['damp', 'tps']}, 09:47:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['dromaeo_dom']}, 09:47:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:47:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['basic_compositor_video']}, 09:47:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:47:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['a11yr', 09:47:00 INFO - 'ts_paint', 09:47:00 INFO - 'tpaint', 09:47:00 INFO - 'sessionrestore', 09:47:00 INFO - 'sessionrestore_no_auto_restore', 09:47:00 INFO - 'tabpaint']}, 09:47:00 INFO - 'other-e10s': {'tests': ['a11yr', 09:47:00 INFO - 'ts_paint', 09:47:00 INFO - 'tpaint', 09:47:00 INFO - 'sessionrestore', 09:47:00 INFO - 'sessionrestore_no_auto_restore', 09:47:00 INFO - 'tabpaint']}, 09:47:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:47:00 INFO - 'ts_paint', 09:47:00 INFO - 'tpaint', 09:47:00 INFO - 'sessionrestore', 09:47:00 INFO - 'sessionrestore_no_auto_restore', 09:47:00 INFO - 'tabpaint']}, 09:47:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:47:00 INFO - 'ts_paint', 09:47:00 INFO - 'tpaint', 09:47:00 INFO - 'sessionrestore', 09:47:00 INFO - 'sessionrestore_no_auto_restore', 09:47:00 INFO - 'tabpaint']}, 09:47:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['a11yr', 09:47:00 INFO - 'ts_paint', 09:47:00 INFO - 'tpaint', 09:47:00 INFO - 'sessionrestore', 09:47:00 INFO - 'sessionrestore_no_auto_restore', 09:47:00 INFO - 'tabpaint']}, 09:47:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['a11yr', 09:47:00 INFO - 'ts_paint', 09:47:00 INFO - 'tpaint', 09:47:00 INFO - 'sessionrestore', 09:47:00 INFO - 'sessionrestore_no_auto_restore', 09:47:00 INFO - 'tabpaint']}, 09:47:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['tsvgx', 09:47:00 INFO - 'tsvgr_opacity', 09:47:00 INFO - 'tart', 09:47:00 INFO - 'tscrollx', 09:47:00 INFO - 'cart', 09:47:00 INFO - 'tsvg_static']}, 09:47:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:47:00 INFO - 'tsvgr_opacity', 09:47:00 INFO - 'tart', 09:47:00 INFO - 'tscrollx', 09:47:00 INFO - 'cart', 09:47:00 INFO - 'tsvg_static']}, 09:47:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:47:00 INFO - 'talos_options': ['--disable-e10s'], 09:47:00 INFO - 'tests': ['tp5o']}, 09:47:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:47:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:47:00 INFO - 'talos_options': ['--disable-e10s', 09:47:00 INFO - '--xperf_path', 09:47:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:00 INFO - 'tests': ['tp5n']}, 09:47:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:47:00 INFO - 'talos_options': ['--xperf_path', 09:47:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:00 INFO - 'tests': ['tp5n']}}, 09:47:00 INFO - 'talos.zip': {'path': '', 09:47:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:47:00 INFO - Running post-action listener: _resource_record_post_action 09:47:00 INFO - [mozharness: 2017-01-07 17:47:00.679017Z] Finished populate-webroot step (success) 09:47:00 INFO - [mozharness: 2017-01-07 17:47:00.679125Z] Running create-virtualenv step. 09:47:00 INFO - Running pre-action listener: _resource_record_pre_action 09:47:00 INFO - Running main action method: create_virtualenv 09:47:00 INFO - Creating virtualenv /builds/slave/test/build/venv 09:47:00 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 09:47:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:47:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:47:00 INFO - Using real prefix '/tools/python27' 09:47:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:47:01 INFO - Installing distribute.............................................................................................................................................................................................done. 09:47:04 INFO - Installing pip.................done. 09:47:04 INFO - Return code: 0 09:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:47:04 INFO - Reading from file tmpfile_stdout 09:47:04 INFO - Output received: 09:47:04 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) 09:47:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:04 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:04 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 09:47:04 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 09:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:04 INFO - 'HOME': '/Users/cltbld', 09:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:04 INFO - 'LOGNAME': 'cltbld', 09:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:04 INFO - 'MOZ_NO_REMOTE': '1', 09:47:04 INFO - 'NO_EM_RESTART': '1', 09:47:04 INFO - 'PAGER': '/bin/cat', 09:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:04 INFO - 'PWD': '/builds/slave/test', 09:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:04 INFO - 'SHELL': '/bin/bash', 09:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:04 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:04 INFO - 'USER': 'cltbld', 09:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:04 INFO - 'XPC_FLAGS': '0x0', 09:47:04 INFO - 'XPC_SERVICE_NAME': '0', 09:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:04 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 09:47:04 INFO - Cleaning up... 09:47:04 INFO - Return code: 0 09:47:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:04 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:04 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 09:47:04 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 09:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:04 INFO - 'HOME': '/Users/cltbld', 09:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:04 INFO - 'LOGNAME': 'cltbld', 09:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:04 INFO - 'MOZ_NO_REMOTE': '1', 09:47:04 INFO - 'NO_EM_RESTART': '1', 09:47:04 INFO - 'PAGER': '/bin/cat', 09:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:04 INFO - 'PWD': '/builds/slave/test', 09:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:04 INFO - 'SHELL': '/bin/bash', 09:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:04 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:04 INFO - 'USER': 'cltbld', 09:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:04 INFO - 'XPC_FLAGS': '0x0', 09:47:04 INFO - 'XPC_SERVICE_NAME': '0', 09:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:05 INFO - Downloading/unpacking psutil>=3.1.1 09:47:05 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 09:47:05 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 09:47:05 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 09:47:05 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 09:47:05 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 09:47:05 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 09:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:47:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:08 INFO - Installing collected packages: psutil 09:47:08 INFO - Running setup.py install for psutil 09:47:09 INFO - building 'psutil._psutil_osx' extension 09:47:09 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 09:47:09 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 09:47:09 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 09:47:09 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 09:47:09 INFO - building 'psutil._psutil_posix' extension 09:47:09 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 09:47:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:47:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:47:09 INFO - ^ 09:47:09 INFO - 1 warning generated. 09:47:09 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 09:47:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:09 INFO - Successfully installed psutil 09:47:09 INFO - Cleaning up... 09:47:09 INFO - Return code: 0 09:47:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:09 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:09 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 09:47:09 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 09:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:09 INFO - 'HOME': '/Users/cltbld', 09:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:09 INFO - 'LOGNAME': 'cltbld', 09:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:09 INFO - 'MOZ_NO_REMOTE': '1', 09:47:09 INFO - 'NO_EM_RESTART': '1', 09:47:09 INFO - 'PAGER': '/bin/cat', 09:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:09 INFO - 'PWD': '/builds/slave/test', 09:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:09 INFO - 'SHELL': '/bin/bash', 09:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:09 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:09 INFO - 'USER': 'cltbld', 09:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:09 INFO - 'XPC_FLAGS': '0x0', 09:47:09 INFO - 'XPC_SERVICE_NAME': '0', 09:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:47:10 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 09:47:10 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 09:47:10 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 09:47:10 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 09:47:10 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 09:47:10 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 09:47:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:47:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:47:12 INFO - Installing collected packages: mozsystemmonitor 09:47:12 INFO - Running setup.py install for mozsystemmonitor 09:47:13 INFO - Successfully installed mozsystemmonitor 09:47:13 INFO - Cleaning up... 09:47:13 INFO - Return code: 0 09:47:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:47:13 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')]} 09:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:13 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')]} 09:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:13 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:13 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 09:47:13 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 09:47:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:13 INFO - 'HOME': '/Users/cltbld', 09:47:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:13 INFO - 'LOGNAME': 'cltbld', 09:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:13 INFO - 'MOZ_NO_REMOTE': '1', 09:47:13 INFO - 'NO_EM_RESTART': '1', 09:47:13 INFO - 'PAGER': '/bin/cat', 09:47:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:13 INFO - 'PWD': '/builds/slave/test', 09:47:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:13 INFO - 'SHELL': '/bin/bash', 09:47:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:13 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:13 INFO - 'USER': 'cltbld', 09:47:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:13 INFO - 'XPC_FLAGS': '0x0', 09:47:13 INFO - 'XPC_SERVICE_NAME': '0', 09:47:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:13 INFO - Downloading/unpacking jsonschema==2.5.1 09:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:47:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:47:15 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 09:47:15 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 09:47:15 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 09:47:15 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 09:47:15 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 09:47:15 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 09:47:16 INFO - Downloading functools32-3.2.3-2.tar.gz 09:47:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:47:16 INFO - warning: no files found matching '*.txt' 09:47:16 INFO - no previously-included directories found matching 'build' 09:47:16 INFO - no previously-included directories found matching 'dist' 09:47:16 INFO - no previously-included directories found matching '.git*' 09:47:16 INFO - Installing collected packages: jsonschema, functools32 09:47:16 INFO - Running setup.py install for functools32 09:47:16 INFO - warning: no files found matching '*.txt' 09:47:16 INFO - no previously-included directories found matching 'build' 09:47:16 INFO - no previously-included directories found matching 'dist' 09:47:16 INFO - no previously-included directories found matching '.git*' 09:47:16 INFO - Successfully installed jsonschema functools32 09:47:16 INFO - Cleaning up... 09:47:16 INFO - Return code: 0 09:47:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:16 INFO - 'HOME': '/Users/cltbld', 09:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:16 INFO - 'LOGNAME': 'cltbld', 09:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:16 INFO - 'MOZ_NO_REMOTE': '1', 09:47:16 INFO - 'NO_EM_RESTART': '1', 09:47:16 INFO - 'PAGER': '/bin/cat', 09:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:16 INFO - 'PWD': '/builds/slave/test', 09:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:16 INFO - 'SHELL': '/bin/bash', 09:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:16 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:16 INFO - 'USER': 'cltbld', 09:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:16 INFO - 'XPC_FLAGS': '0x0', 09:47:16 INFO - 'XPC_SERVICE_NAME': '0', 09:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:47:17 INFO - Cleaning up... 09:47:17 INFO - Return code: 0 09:47:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:47:17 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')]} 09:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:17 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')]} 09:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:17 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:17 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 09:47:17 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 09:47:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:17 INFO - 'HOME': '/Users/cltbld', 09:47:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:17 INFO - 'LOGNAME': 'cltbld', 09:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:17 INFO - 'MOZ_NO_REMOTE': '1', 09:47:17 INFO - 'NO_EM_RESTART': '1', 09:47:17 INFO - 'PAGER': '/bin/cat', 09:47:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:17 INFO - 'PWD': '/builds/slave/test', 09:47:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:17 INFO - 'SHELL': '/bin/bash', 09:47:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:17 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:17 INFO - 'USER': 'cltbld', 09:47:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:17 INFO - 'XPC_FLAGS': '0x0', 09:47:17 INFO - 'XPC_SERVICE_NAME': '0', 09:47:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:47:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:21 INFO - Downloading docopt-0.6.1.tar.gz 09:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:21 INFO - Running setup.py install for blobuploader 09:47:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:47:21 INFO - Running setup.py install for requests 09:47:21 INFO - Running setup.py install for docopt 09:47:22 INFO - Successfully installed blobuploader requests docopt 09:47:22 INFO - Cleaning up... 09:47:22 INFO - Return code: 0 09:47:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:22 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')]} 09:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:22 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')]} 09:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:22 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:22 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 09:47:22 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 09:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:22 INFO - 'HOME': '/Users/cltbld', 09:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:22 INFO - 'LOGNAME': 'cltbld', 09:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:22 INFO - 'MOZ_NO_REMOTE': '1', 09:47:22 INFO - 'NO_EM_RESTART': '1', 09:47:22 INFO - 'PAGER': '/bin/cat', 09:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:22 INFO - 'PWD': '/builds/slave/test', 09:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:22 INFO - 'SHELL': '/bin/bash', 09:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:22 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:22 INFO - 'USER': 'cltbld', 09:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:22 INFO - 'XPC_FLAGS': '0x0', 09:47:22 INFO - 'XPC_SERVICE_NAME': '0', 09:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:22 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-I0tlT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:22 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-4V1lgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:22 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-i83VHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:22 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-c7akRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:22 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-m_xL6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:22 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-WTwz5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Np508Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-2HUFAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-k47hcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-zoPsfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-3U_wJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-9YnpRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-DO1q1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-xdvEEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:23 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-9aJpef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:24 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-AnfjJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:24 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Mv2_RS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:24 INFO - Running setup.py install for manifestparser 09:47:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Running setup.py install for mozcrash 09:47:24 INFO - Running setup.py install for mozdebug 09:47:24 INFO - Running setup.py install for mozdevice 09:47:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Running setup.py install for mozfile 09:47:24 INFO - Running setup.py install for mozhttpd 09:47:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Running setup.py install for mozinfo 09:47:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Running setup.py install for mozInstall 09:47:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Running setup.py install for mozleak 09:47:25 INFO - Running setup.py install for mozlog 09:47:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Running setup.py install for moznetwork 09:47:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Running setup.py install for mozprocess 09:47:26 INFO - Running setup.py install for mozprofile 09:47:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:47:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:47:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:47:26 INFO - Running setup.py install for mozrunner 09:47:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:47:26 INFO - Running setup.py install for mozscreenshot 09:47:26 INFO - Running setup.py install for moztest 09:47:26 INFO - Running setup.py install for mozversion 09:47:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:47:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:26 INFO - Cleaning up... 09:47:26 INFO - Return code: 0 09:47:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:26 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:26 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 09:47:26 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 09:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:26 INFO - 'HOME': '/Users/cltbld', 09:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:26 INFO - 'LOGNAME': 'cltbld', 09:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:26 INFO - 'MOZ_NO_REMOTE': '1', 09:47:26 INFO - 'NO_EM_RESTART': '1', 09:47:26 INFO - 'PAGER': '/bin/cat', 09:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:26 INFO - 'PWD': '/builds/slave/test', 09:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:26 INFO - 'SHELL': '/bin/bash', 09:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:26 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:26 INFO - 'USER': 'cltbld', 09:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:26 INFO - 'XPC_FLAGS': '0x0', 09:47:26 INFO - 'XPC_SERVICE_NAME': '0', 09:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-u4J2Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-8Hhb_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-LVUvRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-2kGRAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-7lU_07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-x7piC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-5kCBNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-R_M5dI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:27 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-toGxJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-nhyM5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-XjxMpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-hnemCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ClvHMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-xkznDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-NsuwUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-W_XDM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:28 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)) 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:28 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-yJ8D3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:28 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)) 09:47:28 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)) 09:47:28 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)) 09:47:28 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)) 09:47:28 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)) 09:47:28 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)) 09:47:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:28 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 09:47:28 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 09:47:28 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 09:47:28 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 09:47:28 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 09:47:28 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 09:47:31 INFO - Downloading blessings-1.6.tar.gz 09:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:47:31 INFO - Installing collected packages: blessings 09:47:31 INFO - Running setup.py install for blessings 09:47:31 INFO - Successfully installed blessings 09:47:31 INFO - Cleaning up... 09:47:31 INFO - Return code: 0 09:47:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:32 INFO - Reading from file tmpfile_stdout 09:47:32 INFO - Current package versions: 09:47:32 INFO - blessings == 1.6 09:47:32 INFO - blobuploader == 1.2.4 09:47:32 INFO - docopt == 0.6.1 09:47:32 INFO - functools32 == 3.2.3-2 09:47:32 INFO - jsonschema == 2.5.1 09:47:32 INFO - manifestparser == 1.1 09:47:32 INFO - mozInstall == 1.12 09:47:32 INFO - mozcrash == 1.0 09:47:32 INFO - mozdebug == 0.1 09:47:32 INFO - mozdevice == 0.49 09:47:32 INFO - mozfile == 1.2 09:47:32 INFO - mozhttpd == 0.7 09:47:32 INFO - mozinfo == 0.9 09:47:32 INFO - mozleak == 0.1 09:47:32 INFO - mozlog == 3.4 09:47:32 INFO - moznetwork == 0.27 09:47:32 INFO - mozprocess == 0.24 09:47:32 INFO - mozprofile == 0.28 09:47:32 INFO - mozrunner == 6.13 09:47:32 INFO - mozscreenshot == 0.1 09:47:32 INFO - mozsystemmonitor == 0.3 09:47:32 INFO - moztest == 0.8 09:47:32 INFO - mozversion == 1.4 09:47:32 INFO - psutil == 3.1.1 09:47:32 INFO - requests == 1.2.3 09:47:32 INFO - wsgiref == 0.1.2 09:47:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:32 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')]} 09:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:32 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')]} 09:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:32 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:32 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 09:47:32 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 09:47:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:32 INFO - 'HOME': '/Users/cltbld', 09:47:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:32 INFO - 'LOGNAME': 'cltbld', 09:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:32 INFO - 'MOZ_NO_REMOTE': '1', 09:47:32 INFO - 'NO_EM_RESTART': '1', 09:47:32 INFO - 'PAGER': '/bin/cat', 09:47:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:32 INFO - 'PWD': '/builds/slave/test', 09:47:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:32 INFO - 'SHELL': '/bin/bash', 09:47:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:32 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:32 INFO - 'USER': 'cltbld', 09:47:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:32 INFO - 'XPC_FLAGS': '0x0', 09:47:32 INFO - 'XPC_SERVICE_NAME': '0', 09:47:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 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)) 09:47:32 INFO - Cleaning up... 09:47:32 INFO - Return code: 0 09:47:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:32 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')]} 09:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:32 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')]} 09:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:32 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 0x10beb0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beb3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd060d816e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, '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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 09:47:32 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 09:47:32 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 09:47:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:32 INFO - 'HOME': '/Users/cltbld', 09:47:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:32 INFO - 'LOGNAME': 'cltbld', 09:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:32 INFO - 'MOZ_NO_REMOTE': '1', 09:47:32 INFO - 'NO_EM_RESTART': '1', 09:47:32 INFO - 'PAGER': '/bin/cat', 09:47:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:32 INFO - 'PWD': '/builds/slave/test', 09:47:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:32 INFO - 'SHELL': '/bin/bash', 09:47:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:32 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:32 INFO - 'USER': 'cltbld', 09:47:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:32 INFO - 'XPC_FLAGS': '0x0', 09:47:32 INFO - 'XPC_SERVICE_NAME': '0', 09:47:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:47:32 INFO - Cleaning up... 09:47:32 INFO - Return code: 0 09:47:32 INFO - Running post-action listener: _resource_record_post_action 09:47:32 INFO - Running post-action listener: _start_resource_monitoring 09:47:32 INFO - Starting resource monitoring. 09:47:32 INFO - [mozharness: 2017-01-07 17:47:32.801853Z] Finished create-virtualenv step (success) 09:47:32 INFO - [mozharness: 2017-01-07 17:47:32.802803Z] Running install step. 09:47:32 INFO - Running pre-action listener: _resource_record_pre_action 09:47:32 INFO - Running main action method: install 09:47:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:33 INFO - Reading from file tmpfile_stdout 09:47:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:33 INFO - Reading from file tmpfile_stdout 09:47:33 INFO - Output received: 09:47:33 INFO - Usage: mozinstall [options] installer 09:47:33 INFO - Options: 09:47:33 INFO - -h, --help show this help message and exit 09:47:33 INFO - -d DEST, --destination=DEST 09:47:33 INFO - Directory to install application into. [default: 09:47:33 INFO - "/builds/slave/test"] 09:47:33 INFO - --app=APP Application being installed. [default: firefox] 09:47:33 INFO - mkdir: /builds/slave/test/build/application 09:47:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:47:54 INFO - Reading from file tmpfile_stdout 09:47:54 INFO - Output received: 09:47:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:47:54 INFO - Running post-action listener: _resource_record_post_action 09:47:54 INFO - [mozharness: 2017-01-07 17:47:54.810842Z] Finished install step (success) 09:47:54 INFO - [mozharness: 2017-01-07 17:47:54.810964Z] Running run-tests step. 09:47:54 INFO - Running pre-action listener: _resource_record_pre_action 09:47:54 INFO - Running main action method: run_tests 09:47:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:54 INFO - 2017-01-07 09:47:54.825 screenresolution[1896:17354] starting screenresolution argv=screenresolution get 09:47:54 INFO - 2017-01-07 09:47:54.828 screenresolution[1896:17354] Display 0: 1600x1200x32@60 09:47:54 INFO - 2017-01-07 09:47:54.834 screenresolution[1897:17356] starting screenresolution argv=screenresolution list 09:47:54 INFO - Available Modes on Display 0 09:47:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:55 INFO - Intel Iris: 09:47:55 INFO - Chipset Model: Intel Iris 09:47:55 INFO - Type: GPU 09:47:55 INFO - Bus: Built-In 09:47:55 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:55 INFO - Vendor: Intel (0x8086) 09:47:55 INFO - Device ID: 0x0a2e 09:47:55 INFO - Revision ID: 0x0009 09:47:55 INFO - Displays: 09:47:55 INFO - Display: 09:47:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:55 INFO - Display Serial Number: 1600x1200 60 09:47:55 INFO - Main Display: Yes 09:47:55 INFO - Mirror: Off 09:47:55 INFO - Online: Yes 09:47:55 INFO - Rotation: Supported 09:47:55 INFO - Return code: 0 09:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:47:55 INFO - Python 2.7.3 09:47:55 INFO - Return code: 0 09:47:55 INFO - grabbing minidump binary from tooltool 09:47:55 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')]} 09:47:55 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 0x10bc8ed30>, '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 0x10bbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc83be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:47:55 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 09:47:55 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 09:47:55 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 09:47:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:47:55 INFO - Return code: 0 09:47:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:47:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:47:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:47:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:47:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:47:55 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/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 09:47:55 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/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0221 --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 09:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4hLRRfxhyk/Render', 09:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:55 INFO - 'HOME': '/Users/cltbld', 09:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:55 INFO - 'LOGNAME': 'cltbld', 09:47:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:47:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:55 INFO - 'MOZ_NO_REMOTE': '1', 09:47:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:47:55 INFO - 'NO_EM_RESTART': '1', 09:47:55 INFO - 'PAGER': '/bin/cat', 09:47:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:55 INFO - 'PWD': '/builds/slave/test', 09:47:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:55 INFO - 'SHELL': '/bin/bash', 09:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.C8AoWYsPMj/Listeners', 09:47:55 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 09:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:55 INFO - 'USER': 'cltbld', 09:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:55 INFO - 'XPC_FLAGS': '0x0', 09:47:55 INFO - 'XPC_SERVICE_NAME': '0', 09:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:55 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/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 09:47:55 INFO - mozversion application_buildid: 20170107082057 09:47:55 INFO - mozversion application_changeset: 2d62b03b833b7e142c530dbcaf4424e357769170 09:47:55 INFO - mozversion application_display_name: Nightly 09:47:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:47:55 INFO - mozversion application_name: Firefox 09:47:55 INFO - mozversion application_remotingname: firefox 09:47:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:47:55 INFO - mozversion application_vendor: Mozilla 09:47:55 INFO - mozversion application_version: 53.0a1 09:47:55 INFO - mozversion platform_buildid: 20170107082057 09:47:55 INFO - mozversion platform_changeset: 2d62b03b833b7e142c530dbcaf4424e357769170 09:47:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:47:55 INFO - mozversion platform_version: 53.0a1 09:47:55 INFO - using testdate: 1483811275 09:47:55 INFO - actual date: 1483811275 09:47:55 INFO - starting webserver on 'localhost:51843' 09:47:55 INFO - SUITE-START | Running 2 tests 09:47:55 INFO - TEST-START | dromaeo_css 09:47:55 INFO - operating with platform_type : mac_ 09:47:55 INFO - Initialising browser for dromaeo_css test... 09:47:55 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmp_9QKDa/profile http://localhost:51843/getInfo.html) 09:47:55 INFO - PROCESS | 1907 | 1483811275936 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:47:58 INFO - PROCESS | 1907 | 2017-01-07 09:47:58.270 plugin-container[1909:17508] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f2b, name = 'com.apple.tsm.portname' 09:47:58 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:58 INFO - PROCESS | 1907 | 2017-01-07 09:47:58.274 plugin-container[1909:17508] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9503, name = 'com.apple.CFPasteboardClient' 09:47:58 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:58 INFO - PROCESS | 1907 | 2017-01-07 09:47:58.274 plugin-container[1909:17508] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:58 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 09:47:58 INFO - PROCESS | 1907 | colorDepth:24 09:47:58 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 09:47:58 INFO - PROCESS | 1907 | __metrics 09:47:58 ERROR - PROCESS | 1907 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 09:47:59 INFO - TEST-INFO | 1907: exit 0 09:47:59 INFO - Browser initialized. 09:47:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:47:59 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmp_9QKDa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:01 INFO - PROCESS | 1910 | 2017-01-07 09:48:01.528 plugin-container[1911:17629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:48:01 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:01 INFO - PROCESS | 1910 | 2017-01-07 09:48:01.529 plugin-container[1911:17629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 09:48:01 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:01 INFO - PROCESS | 1910 | 2017-01-07 09:48:01.529 plugin-container[1911:17629] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:48:11 INFO - PROCESS | 1910 | RSS: Main: 232947712 09:48:11 INFO - PROCESS | 1910 | 09:51:14 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51843/tests/dromaeo/cssquery-ext.html) 09:51:16 INFO - PROCESS | 1910 | RSS: Main: 249974784 09:51:16 INFO - PROCESS | 1910 | 09:52:59 INFO - PROCESS | 1910 | ************************* 09:52:59 INFO - PROCESS | 1910 | A coding exception was thrown and uncaught in a Task. 09:52:59 INFO - PROCESS | 1910 | 09:52:59 INFO - PROCESS | 1910 | Full message: ReferenceError: fetch is not defined 09:52:59 INFO - PROCESS | 1910 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:52:59 INFO - PROCESS | 1910 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:52:59 INFO - PROCESS | 1910 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:52:59 INFO - PROCESS | 1910 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:52:59 INFO - PROCESS | 1910 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:52:59 INFO - PROCESS | 1910 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:52:59 INFO - PROCESS | 1910 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:52:59 INFO - PROCESS | 1910 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:52:59 INFO - PROCESS | 1910 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:52:59 INFO - PROCESS | 1910 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:52:59 INFO - PROCESS | 1910 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:52:59 INFO - PROCESS | 1910 | 09:52:59 INFO - PROCESS | 1910 | ************************* 09:54:18 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-ext.html (next: http://localhost:51843/tests/dromaeo/cssquery-jquery.html) 09:54:19 INFO - PROCESS | 1910 | RSS: Main: 261173248 09:54:19 INFO - PROCESS | 1910 | 09:57:21 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51843/tests/dromaeo/cssquery-mootools.html) 09:57:21 INFO - PROCESS | 1910 | RSS: Main: 268959744 09:57:21 INFO - PROCESS | 1910 | 10:00:23 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51843/tests/dromaeo/cssquery-prototype.html) 10:00:24 INFO - PROCESS | 1910 | RSS: Main: 265035776 10:00:24 INFO - PROCESS | 1910 | 10:03:27 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51843/tests/dromaeo/cssquery-yui.html) 10:03:27 INFO - PROCESS | 1910 | RSS: Main: 270659584 10:03:27 INFO - PROCESS | 1910 | 10:06:30 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-yui.html (next: http://localhost:51843/tests/dromaeo/cssquery-dojo.html) 10:06:31 INFO - PROCESS | 1910 | RSS: Main: 273145856 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | __start_tp_report 10:06:31 INFO - PROCESS | 1910 | _x_x_mozilla_page_load 10:06:31 INFO - PROCESS | 1910 | _x_x_mozilla_page_load_details 10:06:31 INFO - PROCESS | 1910 | |i|pagename|runs| 10:06:31 INFO - PROCESS | 1910 | |0;dojo.html;3101.9379612407747;3303.487959366299;3328.9500657490157;3337.599488061433;3379.746519011074;5509.118541033436;5608.57935654826;5633.211350410946;5668.404817494165;5700.686462244575;5519.558435325174;5584.748686309115;5591.7483713232905;5623.044082505973;5671.035923892938;5074.112030394685;5106.744662766865;5135.178371460567;5187.688738675692;5201.635885488017;5078.669886457368;5190.688558686472;5222.373315202181;5242.423333433319;5286.04851126797;5064.215046667736;5096.949030509684;5175.146100893348;5184.1186998210205;5199.376074871039;5050.671706339113;5126.974365128188;5129.512696293883;5146.099432599318;5156.74216289184;5067.189249720027;5099.949000509984;5157.974210128963;5169.302144210521;5177.534021938043;4800.3759511263315;5136.768845401929;5523.6685798852195;5581.49766521015;5652.152177173416;5358.410574836765;6056.545759068087;6139.600925939844;6184.31972483031;6186.381361863822;6121.727351053096;6191.814245572595;6216.067589861512;6216.943119669621;6277.089821975788;5737.799177028784;5846.093855452412;5874.295084589877;5882.147088672119;5899;6213.502919766408;6265.122882796412;6272.435480806749;6275.529335299871;6284.937150628527;5929.229200203946;5959.821205363846;6010.338862725097;6059.121427393004;6097.176881120992;5073.239014147907;5551.028570000234;6128.533679014772;6146;6173.228346456693;6119.265688117454;6197;6197.1324014637985;6200.255969283625;6231.40801623845;6159.260888693296;6224.408681175281;6226.968865155736;6238.688065596702;6249.7187626556915;4819;4877.853664390074;4880.389951256093;4951.579115775199;4971.950280497149;4409.5149533551285;4435;4441.666874984389;4460.375547423363;4462.3752674625575;5598.272224610856;5610.438956104438;5617.297837770278;5629.577781666391;5746.511546518593;6258.780942666985;6271.560990730605;6279.434850863444;6280.434760871452;6283.560150789493;5505.917411238835;5623.915641265385;5628.212050312959;5643.266375371176;5646.802361917313;5316.787328506894;5369.892602147936;5378.2470454136455;5387.865303367447;5455.645383050089;6257;6300.21247344082;6303.495720342362;6309.179806625201;6312.495000400048;6126;6185.917464443794;6212.720427580679;6272.4982001440685;6291.874162516816;6179.938200618116;6188.938110619017;6213.689315534115;6267.96866015667;6294.905576416266;6002.3097343804275;6083.878322433615;6103.42017508336;6160;6180.783672571439;6207.906881396873;6254.437100660781;6255.093011513396;6260.373962603703;6307.400796924285;4898.093852637274;5244.685318880879;6060.636361818305;6131.019036954066;6131.693415329305;5915.112733090071;5972.372900845348;6039.063945088518;6119.877602448015;6137.324894261538;5592.105263157875;5702.942970570241;5757.222774925332;5820.883582328245;5832.183494310885;6076.6961651916345;6089.208402907594;6090.878182436415;6102.633841969496;6136.32500424944;5732.541396688338;5940.465358117617;5942.227510423617;5956.76172953077;5973.522118230444 10:06:31 INFO - PROCESS | 1910 | |1;ext.html;429.90542080742233;432.4983019697152;495.6431369414021;522.1567169022028;522.287078138341;38916.83249502516;40689.79655101728;40762.796186019026;40911.79544102283;40968.18063638725;28179.29551761207;35806.283874322566;35941.921742347775;41319;41771.37342939858;8054.315383192436;8505.234528892399;8756.4308319959;9367.484788336656;9581.233501319912;9341.579628916716;11458.427078646077;11482.253653512536;11688.24026438283;11703.941480292586;11495.597654082067;11577.710557236094;11584.652460426201;11589.246698964582;11590.072794176487;11464.025557827554;11532.135089868294;11538.307701537935;11547;11560.6531804046;11320;11390.487428065673;11408.486618102206;11409.25839820409;11419.657410277621;34741;34762.478562821576;34896.9530914073;34975.475367869505;34988.65011349879;3866.2267546490534;3912.080661044652;3953.5848735882605;3961.6830653547936;4033.4353190553347;27356.863215683796;27795.305117372227;27908;27966.881324746984;27971.58042629362;3091.48990416581;3189.8245596492184;3300.455424854896;3301.96698033019;3307.7023067924;218990;223832;223868;224269;224492.8775356129;53629;53785.731071344395;53820.461795382325;53932.46067539353;54201.45798542043;55436.90194078648;55499;55824;55930.44069559334;55932.440675593534;13673.17960922328;13691.383887725076;13766.862331376558;13783.72568526182;13837.031407801358;10686.786264274671;10923.453827308604;11626.302421854716;11800.940995295141;11924.046076313874;17213.3975310866;17246.396376126777;17272;17360;17375.304987800348;7505.662245198979;7635.656395462218;7644.350230230381;7739.342155916697;7744.612769361509;734.7024455095694;862.1766213266316;882.3661370808132;885.8626912828522;894.7092195036639;27255.86372068127;29442.263943401587;29575.852120739688;30410;30517;960.5053397500293;988.5205675247499;1045.811753884308;1072.7103682005816;1198.4187668980537;12771.552995645108;13880.681335273133;15422.614434639223;15575.299111540007;15765.763513547307;469791;505192;517073;524202;588952;57238.71380643154;57989.71005145032;57994.7100264496;57999.71000144972;58012.8397432049;1360.6462319796924;1365.487942021742;1383.3705663923092;1390.4160252693894;1395.6510872281929;27756.167314980583;28623.998160064297;28950.27624309325;29637;29648;39766.80116599399;49067.509324907725;49571;49679;49702.005959879876;437545;439290;440908;442692;443377;24081.277561673178;25627.615585765856;25816.225513234633;25876.223713288633;25913;38085;39762;44573.66279011505;45134.64596062124;46064.7696761514;36524.45213321749;37541;38361.23277534489;39101.6089839098;39821;37756.811215943744;37795.62204378031;38676.646317378756;38774.4183837237;39072;36538.8173059133;36688.5145911138;36696.449553256185;36737;37643.05892352713;33506;33623.83188084044;33914.66085339214;34098.31803363968;34291.14272143217;24181.51636967286;24331.51336973286;24702.382440439134;25877.611835822103;26149.73850261473 10:06:31 INFO - PROCESS | 1910 | |2;jquery.html;348.1869833937755;376.4165543407718;380.2641887946823;399.9820008099635;400.52537742774814;16045.679086418279;19820.801791982074;20538.794612053876;20745.962701864883;20849.374518764424;11909.40452977352;12021.459034343454;12027.639170824867;12215.267083974968;12253.632391028283;5182.404023537294;5468.72656367182;5469.507744303012;5479.835604931858;5486.862828429301;3892.649661530462;4080.9591904081026;4180.122174343392;5345.946540534604;5675.914861277084;4180.707350485467;6862.799010173225;6875.381215690587;6887.5178737488395;6889.724411023553;6574.572652777578;6666.266710661824;6750.291219421939;6834.487413443987;6839.281875403086;6015.578909476295;6030.758769649253;6031.366706495843;6031.9396806032255;6045.546584006217;19721.80278197214;24179.395515112086;24404.633930491058;25073;25195.874020629963;2150.258160934475;2541.186820217531;2590.1970389179414;2660.9467810643873;2661.7604415602495;10343.948280258626;10446.373217606892;14114.647133821634;15417.691646167013;16172.838271617251;1829.6157806860576;1881.3603374852617;1883.50087226885;1892.6120145370303;1896.7344571759966;116099;145400.54599454082;146440.26779866032;146475;148164;11698.649040528799;11805.763884722257;12076.154669173073;12119.151659383935;12123.45444454984;15386.461473848361;15387.153706546247;15429.228538573027;15482.993605415611;15502.30239639219;8469.830603387898;8622.78443038929;8641.30869530436;8719.476831390137;8782.824343513093;8345.749627511184;8357.414980951275;8368.3305335573;8462.576871156418;8495.150484951455;4796.2805579163405;4796.352492413549;4800.5199480052415;4850.369451971221;4885.193942999365;1908.7232351308983;1919.539310565482;2029.5839352932614;2079.3450063230034;2080.521480059595;776.4564804636664;796.3828033274143;849.180540778152;868.5917618719192;868.826234753052;20427.79572204259;20946.266880659103;20992.530522863253;21042.263520776698;21169.576608467745;860.4966094834592;874.3660845886808;889.768660148366;891.6299735609779;907.695921866169;10726.356418614909;11058.060064894413;11065.944670276758;11081.889181108085;11332.71668208285;510082;564761;568673;572150;679106;149231.25384373008;151786;152349;158306;160105;964.1804466203811;1007.1238022920106;1039.5062345385882;1039.6309310194922;1040.7606250562412;17728.46814595564;17984.010879401758;18147.45557633273;18190.727139092658;18417.447476575726;57507;59719.40280597138;59820.40179598148;59880.70059649674;60821;81793.59103204445;82655;83976;85170.57414712886;85494.57252713696;5912.787878484814;7018.473614478832;7108.893366599608;7445.516041457288;8272.669093236203;11896.940515297369;12637.87362126367;12650.493980240688;12950;13002.284874332003;9663.371880827724;9936.857261415029;10841;11208;11229.55081796719;10505;10928.726781830517;11086.667399978014;11311.943440282746;11324.6036388726;9721.173700235558;10220.437875916896;11000.339979601249;11483.368414736935;11577.594784182513;6178.783742568989;6958.686859091252;7591.772246832604;8366.958165209135;8464.280536154496;10032.297739158188;10860.131189504978;11124.499397527292;11180.608678696206;11239.831402529133 10:06:31 INFO - PROCESS | 1910 | |3;mootools.html;1299.4931976529156;1411.6188629070152;1439.0789894467537;1449.6448370149315;1450.9056911300768;6540;7003.299670032994;7486.550806951573;7756.650950707218;8487.023992240895;7916;7977.960110199456;8204.876926846102;8235.835283294331;8310.626021829032;2058.619155456238;2343.7656234376595;2356.670066190726;2357.9389274826312;2358.9764102359013;1893.9053047347647;2075.7301550798375;2350.224425939436;2352.8353015288935;2365.396823809926;2079.918442409946;2245.438640339915;2321.442853715105;2322.6980492535945;2330.1262026739973;2230.10795681727;2235.2400183937457;2252.209493452701;2257.413072601119;2259.1189436119844;1996.2114964589052;2309.145616122029;2330.8484948478294;2353.7175538935267;2368.4789346343778;8160.387970902206;8368.414211005233;8460;8472.576371181478;8525.95737021311;5056.721880296583;5173.637845350827;5193.324867767204;5201.973990130063;5205.531502164786;3506.912857014334;3527.171114788023;3550.3254381667402;3575.8390872410805;3587.7667951583326;1217.3304682424719;1219.548766956223;1248.5442813373054;1260.464302671361;1271.167385362589;10664.893351066468;10789.406582637952;10800.297980631156;10825.188110891715;10825.566977320897;4470.173017991617;4560.4983451820935;4714.81140754366;4730.219513780256;4747.406574178228;9403.482808445464;9470.857937130948;9497.43015419077;9549.856752148724;9593.712188634352;5234.842954711365;5236.109861323508;5237.371515418175;5253.737313134405;5257.053730328501;3984.561698213194;4098.8360465581045;4273.337632666941;4728.88177795551;4744.051189762062;3800.2969450651717;3812.256609961031;3868.516435445569;3900.921981560353;3903.3559462688904;1180.3685028509879;1180.6576092932953;1290.5612091888615;1293.9741205176033;1299.2918859221747;681.7239018197638;691.1188235000415;776.0919723923024;850.6639877248452;901.4501154295874;4562.315652652062;5354.65194762347;5364.329458817648;5461.808836690697;5585.720713964285;974.2108891797667;979.8628192053118;985.7190700650424;1029.7168278723411;1036.9066783989476;3694.3165514379925;3743.232637309345;3748.1566647504637;3777.2634336304704;3782.3002744492355;9649.710508684751;9988.25088118394;10010.599576017023;10032.347897386646;10064.993500649876;9957.004299569984;9968.900310996942;9974.850377244347;9980.700578982642;10006.449645269435;6114.633122012693;7637.465377423527;7766.961165194138;7792.142864285038;7862.8427431451055;4175.352820312856;4912.45962944083;5270.787718824615;5612.635178713371;5617.522510586482;8929.776755581162;9210.309226807882;9222.584983675617;9386.530673466435;9393.154616084585;9321.72034838956;9337.346385752931;9418.4819834907;9442.38624489406;9584.76038099053;7124.928750712426;7239.022731931122;7255.383292419992;7264.564126152448;7272.872704730775;7697.230276972482;7889.802754931173;7889.842203156019;7894.171112033384;7900.486468379537;6071.483923866344;7068.858622827412;7167.534110282815;7223.566586004857;7285.307895749896;7029.578225306498;7062.929370706227;7067.5052746307265;7081.858362832818;7103.360697537247;5316.6012549058205;6302.936970630235;6354.618722876642;6424.807255782334;6431.453326467303;4965.577925876341;4989.675671081368;5027.698338099725;5069.5944324454695;5103.540681338697;4901.823562345083;5019.3976722793495;5103;5181.35233095863;5188.118019936544 10:06:31 INFO - PROCESS | 1910 | |4;prototype.html;316.95245713143026;321.7104605854731;341.4076577138664;361.0738455860717;361.26302343219834;4469.731816091037;4881.267809828528;4936.925946110802;5389.757460914258;5471.234027236185;4905.901881962368;5294.682319060849;5320.866978325544;5338.412774594792;5358.374396545693;3851.6726078283377;4215.641670458022;4226.260404429228;4227.387028880826;4230.323148296273;3594.514740510037;3861.189150278431;3968.484097067377;4027.7381970171846;4230.788460576974;3852.2873268445287;4100.2209580179815;4138.3999320098565;4140.7515549067;4173.269677806387;3217.3726123405945;3287.7698561100733;4092.386142078682;4159.168166366739;4162.895927601834;3113.097201811472;3683.4290684943876;3690.704743620504;3692.833822477995;3709.4049558689753;4626.629869610423;4662.696924699918;4759.666823322369;4902.264660300948;4971.701697898137;4405.625521830648;4687.39063921688;4804.567588917014;4833.613310935117;4841.9031619367415;4955.033768415161;5036.5718913892;5348.117560602496;5454.836354909359;5663.490285874291;3371.983140084284;4015.8354077317545;4033.7176397652174;4039.151778126597;4056.513218413809;5632.887342253173;5662.433756624346;5677.347105082927;5891.587588868781;5967.850803729898;3845.038740314921;4063.878083657495;4147.294959856831;4152.280256432042;4174.874753757392;4194.580541945842;4204.579542045771;4205.3481710334945;4212.957870421256;4229.323308270724;4085.8763839944254;4514.954850451453;4837.588804951548;4852.59274810301;4882.292067650171;5206.349206349207;5683.959081891168;5704.3154821421695;5718.51392631631;5757.510611598061;1108.8613923259593;1270.5870592057618;1283.5058502476613;1373.6291201375773;1391.2139641102744;759.8822182561602;911.0843602179767;943.6697155995347;956.2350119904049;958.1651138070517;3880.1075752577067;4391.409068649512;4976.601871850316;5075.898482030412;5093.847184584469;4908.7910440302;5118.078745825787;5578.242853501215;5588.6646801192055;5618.69097199658;4454.621357184611;4696.835610753607;4721.268203428474;4879.229917773508;4917.262410638433;2519.60946053362;2530.7595778401023;2554.8083893708044;2626.0414948543644;2781.928957351394;4819.867331177171;4858.6356023298395;5509.724513774296;5766.933117373215;5814.56940405163;4726.480087190406;4972.950270497248;5308.522232999048;5475.342958844964;5548.9722551386985;5634.690092044895;6049.581986177795;6071.726772295169;6085.513158947185;6101.481374083252;5618.353889302783;5855.268091488564;5881.088861013825;5894.705264736832;5902.4097590240635;5043.2939388486375;5065.898682026413;5114.5652619527755;5487.670739755627;5628.718564071862;4805.567498925114;5233.869153271199;5773.278340207474;5990.670513121646;6002.759889604542;4155.833766649421;4231.942014496376;4247.723897946624;4256.17004684096;4266.914661706686;5778.595498315078;5847.44449277318;5848;5894.705264736832;5896.6756828374855;5298.470152984825;5600.523955463831;5612.522935550361;5632.211490391266;5640.774369025193;4472.217361961709;5094.592432605308;5634.253381451763;5661.952538780339;5703.62926409782;4419.911601768011;5130.97434512825;5404.54061404785;5434.429384914527;5516.234804862405;5018.598512118949;5215.018293048617;5635.802746903839;5730.914036289542;5739.253896993364;5240.0087966572455;5558.415336868376;5561.416051314553;5575.721213939368;5611 10:06:31 INFO - PROCESS | 1910 | |5;yui.html;654.9541532092752;688.8071340024795;693.7883945396655;702.181958018908;769.4344656677341;12540.310282934435;14252.928735356321;14714.926425367872;14878.479253226125;15464;14180.787288190659;14315.997880148394;14362;15010.624734381647;15500.302486388111;862.1723145780059;873.2359185712502;881.0528681667201;882.9735107946772;885.6944354197791;672.2235817630644;691.9931499667971;844.6156998565668;867.9001914779786;871.1984973824098;911.9498427586481;918.2975024106564;919.6183583812709;920.6225447566499;923.1138107416855;911.0570559470982;911.9772005699845;914.3462424366577;915.844306467902;917.9127982841619;677.7356830835978;677.9525433219677;678.8676208139415;678.8947713104477;679.0357692077262;14750.852491475056;15181.620459488491;15581.298841552158;15685.764713529306;15764.921175394164;13767.242801645905;14150.646233844132;14171.716565668628;14205.78691319631;14246.786298205536;11604.767904641943;11733.12001599875;11799.292042477478;11816.704582385422;12111.212771169845;3674.6141655126094;3685.981570092159;3690.4279836625365;3744.794036328001;3834.7699138051526;94953.0504694958;106394.46802765937;108497;109121;110615;8981.685641002534;10263.640772572924;10686.625968091079;10803.919608039134;10847.837282440769;21720.674189887166;22087.889560552416;22137;22172.445688857584;22203.333899983027;6518.44593209573;7582.431317651198;7656.5791756512035;7766.9611651942505;7773.727919522789;4149.605787450188;4233.724807887477;4246.341817018367;4365.105153443536;4448.399466072104;3525.471179323122;3639.3813051781262;3643.544556930384;3645.179834537261;3649.0694872807794;536.9731513424313;560.814931072745;656.6191608866845;666.3469799596422;675.1358261425383;571.4172140677927;660.6927778582982;679.6160169504215;683.1426559667684;693.2166651683666;9946.602135914627;10199.796004079877;10293.691189264335;10357.844632330522;10468.110210632181;601.9157317975487;639.9040143978348;654.3521913305793;662.6057495790094;667.6661669165418;4110.46563946691;4911.189653707098;4956.628252881048;5003.4746351633285;5028.522290382481;106384;107264;107483.46258268658;108562.45718771356;108884.4555777216;90282;95346.09307813944;96317;97587.58443454445;97947.51026244824;55392;82400;82400.58799705963;82872.58563707264;85375.99999999876;9419;9658.517074146404;9662.178714809039;9683.370580912218;9713.660021899199;52577;53315.73342133265;53371.932561349335;54576.454235458725;55709.885802282915;64623;69787.6510617464;70428;70615;70875.64562177156;11703.2978021319;11742.530298787953;11747.706307342385;11772.17594768358;12007.339596322287;12575.182613130117;12717.300548469553;12742.362881856056;12870.485180593047;13025.348732563523;9653.613855445703;12183;12207.572734954234;12301.384930753606;12597.307148106942;11783.705407364541;12014.098942579516;12272.44773985155;12282.508699651913;12552.058595605184;11682.649520514398;12035.578754743892;12386.31875246834;12404.19372740769;12404.503819847467;8079.828424878478;9729.756756081155;9901.257405694458;9976.850347244943;10170.949145254226;6965.746165690224;9088.681896133603;9311.067013890603;9785.755356116155;9924.214905231289 10:06:31 INFO - PROCESS | 1910 | __end_tp_report 10:06:31 INFO - PROCESS | 1910 | __start_cc_report 10:06:31 INFO - PROCESS | 1910 | _x_x_mozilla_cycle_collect,384 10:06:31 INFO - PROCESS | 1910 | __end_cc_report 10:06:31 INFO - PROCESS | 1910 | __startTimestamp1483812391115__endTimestamp 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | ------- Summary: start ------- 10:06:31 INFO - PROCESS | 1910 | Number of tests: 6 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | [#0] dojo.html Cycles:165 Average:5665.61 Median:5846.09 stddev:649.73 (11.1%) stddev-sans-first:619.81 10:06:31 INFO - PROCESS | 1910 | Values: 3101.9 3303.5 3329.0 3337.6 3379.7 5509.1 5608.6 5633.2 5668.4 5700.7 5519.6 5584.7 5591.7 5623.0 5671.0 5074.1 5106.7 5135.2 5187.7 5201.6 5078.7 5190.7 5222.4 5242.4 5286.0 5064.2 5096.9 5175.1 5184.1 5199.4 5050.7 5127.0 5129.5 5146.1 5156.7 5067.2 5099.9 5158.0 5169.3 5177.5 4800.4 5136.8 5523.7 5581.5 5652.2 5358.4 6056.5 6139.6 6184.3 6186.4 6121.7 6191.8 6216.1 6216.9 6277.1 5737.8 5846.1 5874.3 5882.1 5899.0 6213.5 6265.1 6272.4 6275.5 6284.9 5929.2 5959.8 6010.3 6059.1 6097.2 5073.2 5551.0 6128.5 6146.0 6173.2 6119.3 6197.0 6197.1 6200.3 6231.4 6159.3 6224.4 6227.0 6238.7 6249.7 4819.0 4877.9 4880.4 4951.6 4972.0 4409.5 4435.0 4441.7 4460.4 4462.4 5598.3 5610.4 5617.3 5629.6 5746.5 6258.8 6271.6 6279.4 6280.4 6283.6 5505.9 5623.9 5628.2 5643.3 5646.8 5316.8 5369.9 5378.2 5387.9 5455.6 6257.0 6300.2 6303.5 6309.2 6312.5 6126.0 6185.9 6212.7 6272.5 6291.9 6179.9 6188.9 6213.7 6268.0 6294.9 6002.3 6083.9 6103.4 6160.0 6180.8 6207.9 6254.4 6255.1 6260.4 6307.4 4898.1 5244.7 6060.6 6131.0 6131.7 5915.1 5972.4 6039.1 6119.9 6137.3 5592.1 5702.9 5757.2 5820.9 5832.2 6076.7 6089.2 6090.9 6102.6 6136.3 5732.5 5940.5 5942.2 5956.8 5973.5 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | [#1] ext.html Cycles:180 Average:54690.28 Median:34936.21 stddev:110581.48 (316.5%) stddev-sans-first:110816.65 10:06:31 INFO - PROCESS | 1910 | Values: 429.9 432.5 495.6 522.2 522.3 38916.8 40689.8 40762.8 40911.8 40968.2 28179.3 35806.3 35941.9 41319.0 41771.4 8054.3 8505.2 8756.4 9367.5 9581.2 9341.6 11458.4 11482.3 11688.2 11703.9 11495.6 11577.7 11584.7 11589.2 11590.1 11464.0 11532.1 11538.3 11547.0 11560.7 11320.0 11390.5 11408.5 11409.3 11419.7 34741.0 34762.5 34897.0 34975.5 34988.7 3866.2 3912.1 3953.6 3961.7 4033.4 27356.9 27795.3 27908.0 27966.9 27971.6 3091.5 3189.8 3300.5 3302.0 3307.7 218990.0 223832.0 223868.0 224269.0 224492.9 53629.0 53785.7 53820.5 53932.5 54201.5 55436.9 55499.0 55824.0 55930.4 55932.4 13673.2 13691.4 13766.9 13783.7 13837.0 10686.8 10923.5 11626.3 11800.9 11924.0 17213.4 17246.4 17272.0 17360.0 17375.3 7505.7 7635.7 7644.4 7739.3 7744.6 734.7 862.2 882.4 885.9 894.7 27255.9 29442.3 29575.9 30410.0 30517.0 960.5 988.5 1045.8 1072.7 1198.4 12771.6 13880.7 15422.6 15575.3 15765.8 469791.0 505192.0 517073.0 524202.0 588952.0 57238.7 57989.7 57994.7 57999.7 58012.8 1360.6 1365.5 1383.4 1390.4 1395.7 27756.2 28624.0 28950.3 29637.0 29648.0 39766.8 49067.5 49571.0 49679.0 49702.0 437545.0 439290.0 440908.0 442692.0 443377.0 24081.3 25627.6 25816.2 25876.2 25913.0 38085.0 39762.0 44573.7 45134.6 46064.8 36524.5 37541.0 38361.2 39101.6 39821.0 37756.8 37795.6 38676.6 38774.4 39072.0 36538.8 36688.5 36696.4 36737.0 37643.1 33506.0 33623.8 33914.7 34098.3 34291.1 24181.5 24331.5 24702.4 25877.6 26149.7 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | [#2] jquery.html Cycles:180 Average:35958.22 Median:25134.44 stddev:98823.79 (393.2%) stddev-sans-first:99064.84 10:06:31 INFO - PROCESS | 1910 | Values: 348.2 376.4 380.3 400.0 400.5 16045.7 19820.8 20538.8 20746.0 20849.4 11909.4 12021.5 12027.6 12215.3 12253.6 5182.4 5468.7 5469.5 5479.8 5486.9 3892.6 4081.0 4180.1 5345.9 5675.9 4180.7 6862.8 6875.4 6887.5 6889.7 6574.6 6666.3 6750.3 6834.5 6839.3 6015.6 6030.8 6031.4 6031.9 6045.5 19721.8 24179.4 24404.6 25073.0 25195.9 2150.3 2541.2 2590.2 2660.9 2661.8 10343.9 10446.4 14114.6 15417.7 16172.8 1829.6 1881.4 1883.5 1892.6 1896.7 116099.0 145400.5 146440.3 146475.0 148164.0 11698.6 11805.8 12076.2 12119.2 12123.5 15386.5 15387.2 15429.2 15483.0 15502.3 8469.8 8622.8 8641.3 8719.5 8782.8 8345.7 8357.4 8368.3 8462.6 8495.2 4796.3 4796.4 4800.5 4850.4 4885.2 1908.7 1919.5 2029.6 2079.3 2080.5 776.5 796.4 849.2 868.6 868.8 20427.8 20946.3 20992.5 21042.3 21169.6 860.5 874.4 889.8 891.6 907.7 10726.4 11058.1 11065.9 11081.9 11332.7 510082.0 564761.0 568673.0 572150.0 679106.0 149231.3 151786.0 152349.0 158306.0 160105.0 964.2 1007.1 1039.5 1039.6 1040.8 17728.5 17984.0 18147.5 18190.7 18417.4 57507.0 59719.4 59820.4 59880.7 60821.0 81793.6 82655.0 83976.0 85170.6 85494.6 5912.8 7018.5 7108.9 7445.5 8272.7 11896.9 12637.9 12650.5 12950.0 13002.3 9663.4 9936.9 10841.0 11208.0 11229.6 10505.0 10928.7 11086.7 11311.9 11324.6 9721.2 10220.4 11000.3 11483.4 11577.6 6178.8 6958.7 7591.8 8367.0 8464.3 10032.3 10860.1 11124.5 11180.6 11239.8 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | [#3] mootools.html Cycles:180 Average:5377.65 Median:5190.72 stddev:2949.26 (56.8%) stddev-sans-first:2941.61 10:06:31 INFO - PROCESS | 1910 | Values: 1299.5 1411.6 1439.1 1449.6 1450.9 6540.0 7003.3 7486.6 7756.7 8487.0 7916.0 7978.0 8204.9 8235.8 8310.6 2058.6 2343.8 2356.7 2357.9 2359.0 1893.9 2075.7 2350.2 2352.8 2365.4 2079.9 2245.4 2321.4 2322.7 2330.1 2230.1 2235.2 2252.2 2257.4 2259.1 1996.2 2309.1 2330.8 2353.7 2368.5 8160.4 8368.4 8460.0 8472.6 8526.0 5056.7 5173.6 5193.3 5202.0 5205.5 3506.9 3527.2 3550.3 3575.8 3587.8 1217.3 1219.5 1248.5 1260.5 1271.2 10664.9 10789.4 10800.3 10825.2 10825.6 4470.2 4560.5 4714.8 4730.2 4747.4 9403.5 9470.9 9497.4 9549.9 9593.7 5234.8 5236.1 5237.4 5253.7 5257.1 3984.6 4098.8 4273.3 4728.9 4744.1 3800.3 3812.3 3868.5 3900.9 3903.4 1180.4 1180.7 1290.6 1294.0 1299.3 681.7 691.1 776.1 850.7 901.5 4562.3 5354.7 5364.3 5461.8 5585.7 974.2 979.9 985.7 1029.7 1036.9 3694.3 3743.2 3748.2 3777.3 3782.3 9649.7 9988.3 10010.6 10032.3 10065.0 9957.0 9968.9 9974.9 9980.7 10006.4 6114.6 7637.5 7767.0 7792.1 7862.8 4175.4 4912.5 5270.8 5612.6 5617.5 8929.8 9210.3 9222.6 9386.5 9393.2 9321.7 9337.3 9418.5 9442.4 9584.8 7124.9 7239.0 7255.4 7264.6 7272.9 7697.2 7889.8 7889.8 7894.2 7900.5 6071.5 7068.9 7167.5 7223.6 7285.3 7029.6 7062.9 7067.5 7081.9 7103.4 5316.6 6302.9 6354.6 6424.8 6431.5 4965.6 4989.7 5027.7 5069.6 5103.5 4901.8 5019.4 5103.0 5181.4 5188.1 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | [#4] prototype.html Cycles:180 Average:4528.75 Median:4927.09 stddev:1371.71 (27.8%) stddev-sans-first:1338.64 10:06:31 INFO - PROCESS | 1910 | Values: 317.0 321.7 341.4 361.1 361.3 4469.7 4881.3 4936.9 5389.8 5471.2 4905.9 5294.7 5320.9 5338.4 5358.4 3851.7 4215.6 4226.3 4227.4 4230.3 3594.5 3861.2 3968.5 4027.7 4230.8 3852.3 4100.2 4138.4 4140.8 4173.3 3217.4 3287.8 4092.4 4159.2 4162.9 3113.1 3683.4 3690.7 3692.8 3709.4 4626.6 4662.7 4759.7 4902.3 4971.7 4405.6 4687.4 4804.6 4833.6 4841.9 4955.0 5036.6 5348.1 5454.8 5663.5 3372.0 4015.8 4033.7 4039.2 4056.5 5632.9 5662.4 5677.3 5891.6 5967.9 3845.0 4063.9 4147.3 4152.3 4174.9 4194.6 4204.6 4205.3 4213.0 4229.3 4085.9 4515.0 4837.6 4852.6 4882.3 5206.3 5684.0 5704.3 5718.5 5757.5 1108.9 1270.6 1283.5 1373.6 1391.2 759.9 911.1 943.7 956.2 958.2 3880.1 4391.4 4976.6 5075.9 5093.8 4908.8 5118.1 5578.2 5588.7 5618.7 4454.6 4696.8 4721.3 4879.2 4917.3 2519.6 2530.8 2554.8 2626.0 2781.9 4819.9 4858.6 5509.7 5766.9 5814.6 4726.5 4973.0 5308.5 5475.3 5549.0 5634.7 6049.6 6071.7 6085.5 6101.5 5618.4 5855.3 5881.1 5894.7 5902.4 5043.3 5065.9 5114.6 5487.7 5628.7 4805.6 5233.9 5773.3 5990.7 6002.8 4155.8 4231.9 4247.7 4256.2 4266.9 5778.6 5847.4 5848.0 5894.7 5896.7 5298.5 5600.5 5612.5 5632.2 5640.8 4472.2 5094.6 5634.3 5662.0 5703.6 4419.9 5131.0 5404.5 5434.4 5516.2 5018.6 5215.0 5635.8 5730.9 5739.3 5240.0 5558.4 5561.4 5575.7 5611.0 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - PROCESS | 1910 | [#5] yui.html Cycles:180 Average:20628.63 Median:5016.00 stddev:30529.57 (608.6%) stddev-sans-first:30578.37 10:06:31 INFO - PROCESS | 1910 | Values: 655.0 688.8 693.8 702.2 769.4 12540.3 14252.9 14714.9 14878.5 15464.0 14180.8 14316.0 14362.0 15010.6 15500.3 862.2 873.2 881.1 883.0 885.7 672.2 692.0 844.6 867.9 871.2 911.9 918.3 919.6 920.6 923.1 911.1 912.0 914.3 915.8 917.9 677.7 678.0 678.9 678.9 679.0 14750.9 15181.6 15581.3 15685.8 15764.9 13767.2 14150.6 14171.7 14205.8 14246.8 11604.8 11733.1 11799.3 11816.7 12111.2 3674.6 3686.0 3690.4 3744.8 3834.8 94953.1 106394.5 108497.0 109121.0 110615.0 8981.7 10263.6 10686.6 10803.9 10847.8 21720.7 22087.9 22137.0 22172.4 22203.3 6518.4 7582.4 7656.6 7767.0 7773.7 4149.6 4233.7 4246.3 4365.1 4448.4 3525.5 3639.4 3643.5 3645.2 3649.1 537.0 560.8 656.6 666.3 675.1 571.4 660.7 679.6 683.1 693.2 9946.6 10199.8 10293.7 10357.8 10468.1 601.9 639.9 654.4 662.6 667.7 4110.5 4911.2 4956.6 5003.5 5028.5 106384.0 107264.0 107483.5 108562.5 108884.5 90282.0 95346.1 96317.0 97587.6 97947.5 55392.0 82400.0 82400.6 82872.6 85376.0 9419.0 9658.5 9662.2 9683.4 9713.7 52577.0 53315.7 53371.9 54576.5 55709.9 64623.0 69787.7 70428.0 70615.0 70875.6 11703.3 11742.5 11747.7 11772.2 12007.3 12575.2 12717.3 12742.4 12870.5 13025.3 9653.6 12183.0 12207.6 12301.4 12597.3 11783.7 12014.1 12272.4 12282.5 12552.1 11682.6 12035.6 12386.3 12404.2 12404.5 8079.8 9729.8 9901.3 9976.9 10170.9 6965.7 9088.7 9311.1 9785.8 9924.2 10:06:31 INFO - PROCESS | 1910 | -------- Summary: end -------- 10:06:31 INFO - PROCESS | 1910 | 10:06:31 INFO - TEST-INFO | 1910: exit 0 10:06:31 INFO - TEST-OK | dromaeo_css | took 1116335ms 10:06:31 INFO - TEST-START | kraken 10:06:31 INFO - operating with platform_type : mac_ 10:06:31 INFO - Initialising browser for kraken test... 10:06:31 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpF5_nDe/profile http://localhost:51843/getInfo.html) 10:06:32 INFO - PROCESS | 1914 | 1483812392245 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 10:06:34 INFO - PROCESS | 1914 | 2017-01-07 10:06:34.656 plugin-container[1915:19639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 10:06:34 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:34 INFO - PROCESS | 1914 | 2017-01-07 10:06:34.659 plugin-container[1915:19639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 10:06:34 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:34 INFO - PROCESS | 1914 | 2017-01-07 10:06:34.659 plugin-container[1915:19639] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:34 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 10:06:34 INFO - PROCESS | 1914 | colorDepth:24 10:06:34 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 10:06:34 INFO - PROCESS | 1914 | __metrics 10:06:34 ERROR - PROCESS | 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 10:06:35 INFO - TEST-INFO | 1914: exit 0 10:06:35 INFO - Browser initialized. 10:06:35 INFO - Running cycle 1/1 for kraken test... 10:06:35 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpF5_nDe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:37 INFO - PROCESS | 1916 | 2017-01-07 10:06:37.693 plugin-container[1917:19754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 10:06:37 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:37 INFO - PROCESS | 1916 | 2017-01-07 10:06:37.694 plugin-container[1917:19754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x885b, name = 'com.apple.CFPasteboardClient' 10:06:37 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:37 INFO - PROCESS | 1916 | 2017-01-07 10:06:37.694 plugin-container[1917:19754] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:47 INFO - PROCESS | 1916 | RSS: Main: 233234432 10:06:47 INFO - PROCESS | 1916 | 10:06:50 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:51 INFO - PROCESS | 1916 | RSS: Main: 246235136 10:06:51 INFO - PROCESS | 1916 | 10:06:53 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-dft) 10:06:54 INFO - PROCESS | 1916 | RSS: Main: 253722624 10:06:54 INFO - PROCESS | 1916 | 10:06:57 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-fft) 10:06:58 INFO - PROCESS | 1916 | RSS: Main: 256290816 10:06:58 INFO - PROCESS | 1916 | 10:07:00 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-oscillator) 10:07:01 INFO - PROCESS | 1916 | RSS: Main: 258609152 10:07:01 INFO - PROCESS | 1916 | 10:07:03 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51843/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:03 INFO - PROCESS | 1916 | RSS: Main: 259264512 10:07:03 INFO - PROCESS | 1916 | 10:07:06 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51843/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:07 INFO - PROCESS | 1916 | RSS: Main: 264921088 10:07:07 INFO - PROCESS | 1916 | 10:07:10 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51843/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:11 INFO - PROCESS | 1916 | RSS: Main: 264507392 10:07:11 INFO - PROCESS | 1916 | 10:07:14 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51843/tests/kraken/driver.html?testName=json-parse-financial) 10:07:14 INFO - PROCESS | 1916 | RSS: Main: 264249344 10:07:14 INFO - PROCESS | 1916 | 10:07:16 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51843/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:07:16 INFO - PROCESS | 1916 | RSS: Main: 263245824 10:07:16 INFO - PROCESS | 1916 | 10:07:19 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:07:19 INFO - PROCESS | 1916 | RSS: Main: 263319552 10:07:19 INFO - PROCESS | 1916 | 10:07:22 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:22 INFO - PROCESS | 1916 | RSS: Main: 263360512 10:07:22 INFO - PROCESS | 1916 | 10:07:25 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:26 INFO - PROCESS | 1916 | RSS: Main: 263639040 10:07:26 INFO - PROCESS | 1916 | 10:07:29 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:29 INFO - PROCESS | 1916 | RSS: Main: 263487488 10:07:29 INFO - PROCESS | 1916 | 10:07:31 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51843/tests/kraken/driver.html?testName=ai-astar) 10:07:32 INFO - PROCESS | 1916 | RSS: Main: 265039872 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | __start_tp_report 10:07:32 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 10:07:32 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 10:07:32 INFO - PROCESS | 1916 | |i|pagename|runs| 10:07:32 INFO - PROCESS | 1916 | |0;ai-astar;107;117;117;117;118;113;115;118;117;118 10:07:32 INFO - PROCESS | 1916 | |1;audio-beat-detection;143;103;97;129;145;145;144;111;129;110 10:07:32 INFO - PROCESS | 1916 | |2;audio-dft;220;273;214;212;203;197;196;197;233;244 10:07:32 INFO - PROCESS | 1916 | |3;audio-fft;94;51;61;77;71;71;54;54;78;84 10:07:32 INFO - PROCESS | 1916 | |4;audio-oscillator;89;69;83;72;73;70;81;70;77;96 10:07:32 INFO - PROCESS | 1916 | |5;imaging-gaussian-blur;88;86;92;88;90;73;137;133;99;86 10:07:32 INFO - PROCESS | 1916 | |6;imaging-darkroom;98;96;94;139;120;118;108;153;153;113 10:07:32 INFO - PROCESS | 1916 | |7;imaging-desaturate;98;125;93;92;92;142;94;90;93;92 10:07:32 INFO - PROCESS | 1916 | |8;json-parse-financial;62;62;61;62;63;62;61;65;73;94 10:07:32 INFO - PROCESS | 1916 | |9;json-stringify-tinderbox;70;75;72;68;90;92;105;106;59;98 10:07:32 INFO - PROCESS | 1916 | |10;stanford-crypto-aes;110;117;79;97;108;109;112;83;104;111 10:07:32 INFO - PROCESS | 1916 | |11;stanford-crypto-ccm;142;146;142;134;154;157;161;155;192;162 10:07:32 INFO - PROCESS | 1916 | |12;stanford-crypto-pbkdf2;176;177;176;175;176;176;176;175;177;177 10:07:32 INFO - PROCESS | 1916 | |13;stanford-crypto-sha256-iterative;71;85;83;78;69;69;71;83;71;62 10:07:32 INFO - PROCESS | 1916 | __end_tp_report 10:07:32 INFO - PROCESS | 1916 | __start_cc_report 10:07:32 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,922 10:07:32 INFO - PROCESS | 1916 | __end_cc_report 10:07:32 INFO - PROCESS | 1916 | __startTimestamp1483812452027__endTimestamp 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | ------- Summary: start ------- 10:07:32 INFO - PROCESS | 1916 | Number of tests: 14 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#0] ai-astar Cycles:10 Average:115.70 Median:117.00 stddev:3.43 (2.9%) stddev-sans-first:1.66 10:07:32 INFO - PROCESS | 1916 | Values: 107.0 117.0 117.0 117.0 118.0 113.0 115.0 118.0 117.0 118.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:143.50 stddev:18.86 (13.1%) stddev-sans-first:18.93 10:07:32 INFO - PROCESS | 1916 | Values: 143.0 103.0 97.0 129.0 145.0 145.0 144.0 111.0 129.0 110.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#2] audio-dft Cycles:10 Average:218.90 Median:217.00 stddev:24.87 (11.5%) stddev-sans-first:26.37 10:07:32 INFO - PROCESS | 1916 | Values: 220.0 273.0 214.0 212.0 203.0 197.0 196.0 197.0 233.0 244.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#3] audio-fft Cycles:10 Average:69.50 Median:74.00 stddev:14.29 (19.3%) stddev-sans-first:12.10 10:07:32 INFO - PROCESS | 1916 | Values: 94.0 51.0 61.0 77.0 71.0 71.0 54.0 54.0 78.0 84.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.00 stddev:9.13 (11.6%) stddev-sans-first:8.77 10:07:32 INFO - PROCESS | 1916 | Values: 89.0 69.0 83.0 72.0 73.0 70.0 81.0 70.0 77.0 96.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:88.00 stddev:20.96 (23.8%) stddev-sans-first:21.96 10:07:32 INFO - PROCESS | 1916 | Values: 88.0 86.0 92.0 88.0 90.0 73.0 137.0 133.0 99.0 86.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#6] imaging-darkroom Cycles:10 Average:119.20 Median:153.00 stddev:22.28 (14.6%) stddev-sans-first:22.27 10:07:32 INFO - PROCESS | 1916 | Values: 98.0 96.0 94.0 139.0 120.0 118.0 108.0 153.0 153.0 113.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#7] imaging-desaturate Cycles:10 Average:101.10 Median:92.50 stddev:17.66 (19.1%) stddev-sans-first:18.70 10:07:32 INFO - PROCESS | 1916 | Values: 98.0 125.0 93.0 92.0 92.0 142.0 94.0 90.0 93.0 92.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:62.50 stddev:10.30 (16.5%) stddev-sans-first:10.79 10:07:32 INFO - PROCESS | 1916 | Values: 62.0 62.0 61.0 62.0 63.0 62.0 61.0 65.0 73.0 94.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#9] json-stringify-tinderbox Cycles:10 Average:83.50 Median:73.50 stddev:16.73 (22.8%) stddev-sans-first:17.02 10:07:32 INFO - PROCESS | 1916 | Values: 70.0 75.0 72.0 68.0 90.0 92.0 105.0 106.0 59.0 98.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#10] stanford-crypto-aes Cycles:10 Average:103.00 Median:114.50 stddev:12.75 (11.1%) stddev-sans-first:13.27 10:07:32 INFO - PROCESS | 1916 | Values: 110.0 117.0 79.0 97.0 108.0 109.0 112.0 83.0 104.0 111.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:156.00 stddev:16.04 (10.3%) stddev-sans-first:16.37 10:07:32 INFO - PROCESS | 1916 | Values: 142.0 146.0 142.0 134.0 154.0 157.0 161.0 155.0 192.0 162.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:176.00 stddev:0.74 (0.4%) stddev-sans-first:0.78 10:07:32 INFO - PROCESS | 1916 | Values: 176.0 177.0 176.0 175.0 176.0 176.0 176.0 175.0 177.0 177.0 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - PROCESS | 1916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:74.50 stddev:7.60 (10.2%) stddev-sans-first:7.97 10:07:32 INFO - PROCESS | 1916 | Values: 71.0 85.0 83.0 78.0 69.0 69.0 71.0 83.0 71.0 62.0 10:07:32 INFO - PROCESS | 1916 | -------- Summary: end -------- 10:07:32 INFO - PROCESS | 1916 | 10:07:32 INFO - TEST-INFO | 1916: exit 0 10:07:33 INFO - TEST-OK | kraken | took 61271ms 10:07:33 INFO - SUITE-END | took 1177s 10:07:33 INFO - Completed test suite (00:19:37) 10:07:33 INFO - javascript benchmark 10:07:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7148.399283096675, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3101.9379612407747, 3303.487959366299, 3328.9500657490157, 3337.599488061433, 3379.746519011074, 5509.118541033436, 5608.57935654826, 5633.211350410946, 5668.404817494165, 5700.686462244575, 5519.558435325174, 5584.748686309115, 5591.7483713232905, 5623.044082505973, 5671.035923892938, 5074.112030394685, 5106.744662766865, 5135.178371460567, 5187.688738675692, 5201.635885488017, 5078.669886457368, 5190.688558686472, 5222.373315202181, 5242.423333433319, 5286.04851126797, 5064.215046667736, 5096.949030509684, 5175.146100893348, 5184.1186998210205, 5199.376074871039, 5050.671706339113, 5126.974365128188, 5129.512696293883, 5146.099432599318, 5156.74216289184, 5067.189249720027, 5099.949000509984, 5157.974210128963, 5169.302144210521, 5177.534021938043, 4800.3759511263315, 5136.768845401929, 5523.6685798852195, 5581.49766521015, 5652.152177173416, 5358.410574836765, 6056.545759068087, 6139.600925939844, 6184.31972483031, 6186.381361863822, 6121.727351053096, 6191.814245572595, 6216.067589861512, 6216.943119669621, 6277.089821975788, 5737.799177028784, 5846.093855452412, 5874.295084589877, 5882.147088672119, 5899.0, 6213.502919766408, 6265.122882796412, 6272.435480806749, 6275.529335299871, 6284.937150628527, 5929.229200203946, 5959.821205363846, 6010.338862725097, 6059.121427393004, 6097.176881120992, 5073.239014147907, 5551.028570000234, 6128.533679014772, 6146.0, 6173.228346456693, 6119.265688117454, 6197.0, 6197.1324014637985, 6200.255969283625, 6231.40801623845, 6159.260888693296, 6224.408681175281, 6226.968865155736, 6238.688065596702, 6249.7187626556915, 4819.0, 4877.853664390074, 4880.389951256093, 4951.579115775199, 4971.950280497149, 4409.5149533551285, 4435.0, 4441.666874984389, 4460.375547423363, 4462.3752674625575, 5598.272224610856, 5610.438956104438, 5617.297837770278, 5629.577781666391, 5746.511546518593, 6258.780942666985, 6271.560990730605, 6279.434850863444, 6280.434760871452, 6283.560150789493, 5505.917411238835, 5623.915641265385, 5628.212050312959, 5643.266375371176, 5646.802361917313, 5316.787328506894, 5369.892602147936, 5378.2470454136455, 5387.865303367447, 5455.645383050089, 6257.0, 6300.21247344082, 6303.495720342362, 6309.179806625201, 6312.495000400048, 6126.0, 6185.917464443794, 6212.720427580679, 6272.4982001440685, 6291.874162516816, 6179.938200618116, 6188.938110619017, 6213.689315534115, 6267.96866015667, 6294.905576416266, 6002.3097343804275, 6083.878322433615, 6103.42017508336, 6160.0, 6180.783672571439, 6207.906881396873, 6254.437100660781, 6255.093011513396, 6260.373962603703, 6307.400796924285, 4898.093852637274, 5244.685318880879, 6060.636361818305, 6131.019036954066, 6131.693415329305, 5915.112733090071, 5972.372900845348, 6039.063945088518, 6119.877602448015, 6137.324894261538, 5592.105263157875, 5702.942970570241, 5757.222774925332, 5820.883582328245, 5832.183494310885, 6076.6961651916345, 6089.208402907594, 6090.878182436415, 6102.633841969496, 6136.32500424944, 5732.541396688338, 5940.465358117617, 5942.227510423617, 5956.76172953077, 5973.522118230444], "value": 5624.0263487513785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.90542080742233, 432.4983019697152, 495.6431369414021, 522.1567169022028, 522.287078138341, 38916.83249502516, 40689.79655101728, 40762.796186019026, 40911.79544102283, 40968.18063638725, 28179.29551761207, 35806.283874322566, 35941.921742347775, 41319.0, 41771.37342939858, 8054.315383192436, 8505.234528892399, 8756.4308319959, 9367.484788336656, 9581.233501319912, 9341.579628916716, 11458.427078646077, 11482.253653512536, 11688.24026438283, 11703.941480292586, 11495.597654082067, 11577.710557236094, 11584.652460426201, 11589.246698964582, 11590.072794176487, 11464.025557827554, 11532.135089868294, 11538.307701537935, 11547.0, 11560.6531804046, 11320.0, 11390.487428065673, 11408.486618102206, 11409.25839820409, 11419.657410277621, 34741.0, 34762.478562821576, 34896.9530914073, 34975.475367869505, 34988.65011349879, 3866.2267546490534, 3912.080661044652, 3953.5848735882605, 3961.6830653547936, 4033.4353190553347, 27356.863215683796, 27795.305117372227, 27908.0, 27966.881324746984, 27971.58042629362, 3091.48990416581, 3189.8245596492184, 3300.455424854896, 3301.96698033019, 3307.7023067924, 218990.0, 223832.0, 223868.0, 224269.0, 224492.8775356129, 53629.0, 53785.731071344395, 53820.461795382325, 53932.46067539353, 54201.45798542043, 55436.90194078648, 55499.0, 55824.0, 55930.44069559334, 55932.440675593534, 13673.17960922328, 13691.383887725076, 13766.862331376558, 13783.72568526182, 13837.031407801358, 10686.786264274671, 10923.453827308604, 11626.302421854716, 11800.940995295141, 11924.046076313874, 17213.3975310866, 17246.396376126777, 17272.0, 17360.0, 17375.304987800348, 7505.662245198979, 7635.656395462218, 7644.350230230381, 7739.342155916697, 7744.612769361509, 734.7024455095694, 862.1766213266316, 882.3661370808132, 885.8626912828522, 894.7092195036639, 27255.86372068127, 29442.263943401587, 29575.852120739688, 30410.0, 30517.0, 960.5053397500293, 988.5205675247499, 1045.811753884308, 1072.7103682005816, 1198.4187668980537, 12771.552995645108, 13880.681335273133, 15422.614434639223, 15575.299111540007, 15765.763513547307, 469791.0, 505192.0, 517073.0, 524202.0, 588952.0, 57238.71380643154, 57989.71005145032, 57994.7100264496, 57999.71000144972, 58012.8397432049, 1360.6462319796924, 1365.487942021742, 1383.3705663923092, 1390.4160252693894, 1395.6510872281929, 27756.167314980583, 28623.998160064297, 28950.27624309325, 29637.0, 29648.0, 39766.80116599399, 49067.509324907725, 49571.0, 49679.0, 49702.005959879876, 437545.0, 439290.0, 440908.0, 442692.0, 443377.0, 24081.277561673178, 25627.615585765856, 25816.225513234633, 25876.223713288633, 25913.0, 38085.0, 39762.0, 44573.66279011505, 45134.64596062124, 46064.7696761514, 36524.45213321749, 37541.0, 38361.23277534489, 39101.6089839098, 39821.0, 37756.811215943744, 37795.62204378031, 38676.646317378756, 38774.4183837237, 39072.0, 36538.8173059133, 36688.5145911138, 36696.449553256185, 36737.0, 37643.05892352713, 33506.0, 33623.83188084044, 33914.66085339214, 34098.31803363968, 34291.14272143217, 24181.51636967286, 24331.51336973286, 24702.382440439134, 25877.611835822103, 26149.73850261473], "value": 18902.30201857535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.1869833937755, 376.4165543407718, 380.2641887946823, 399.9820008099635, 400.52537742774814, 16045.679086418279, 19820.801791982074, 20538.794612053876, 20745.962701864883, 20849.374518764424, 11909.40452977352, 12021.459034343454, 12027.639170824867, 12215.267083974968, 12253.632391028283, 5182.404023537294, 5468.72656367182, 5469.507744303012, 5479.835604931858, 5486.862828429301, 3892.649661530462, 4080.9591904081026, 4180.122174343392, 5345.946540534604, 5675.914861277084, 4180.707350485467, 6862.799010173225, 6875.381215690587, 6887.5178737488395, 6889.724411023553, 6574.572652777578, 6666.266710661824, 6750.291219421939, 6834.487413443987, 6839.281875403086, 6015.578909476295, 6030.758769649253, 6031.366706495843, 6031.9396806032255, 6045.546584006217, 19721.80278197214, 24179.395515112086, 24404.633930491058, 25073.0, 25195.874020629963, 2150.258160934475, 2541.186820217531, 2590.1970389179414, 2660.9467810643873, 2661.7604415602495, 10343.948280258626, 10446.373217606892, 14114.647133821634, 15417.691646167013, 16172.838271617251, 1829.6157806860576, 1881.3603374852617, 1883.50087226885, 1892.6120145370303, 1896.7344571759966, 116099.0, 145400.54599454082, 146440.26779866032, 146475.0, 148164.0, 11698.649040528799, 11805.763884722257, 12076.154669173073, 12119.151659383935, 12123.45444454984, 15386.461473848361, 15387.153706546247, 15429.228538573027, 15482.993605415611, 15502.30239639219, 8469.830603387898, 8622.78443038929, 8641.30869530436, 8719.476831390137, 8782.824343513093, 8345.749627511184, 8357.414980951275, 8368.3305335573, 8462.576871156418, 8495.150484951455, 4796.2805579163405, 4796.352492413549, 4800.5199480052415, 4850.369451971221, 4885.193942999365, 1908.7232351308983, 1919.539310565482, 2029.5839352932614, 2079.3450063230034, 2080.521480059595, 776.4564804636664, 796.3828033274143, 849.180540778152, 868.5917618719192, 868.826234753052, 20427.79572204259, 20946.266880659103, 20992.530522863253, 21042.263520776698, 21169.576608467745, 860.4966094834592, 874.3660845886808, 889.768660148366, 891.6299735609779, 907.695921866169, 10726.356418614909, 11058.060064894413, 11065.944670276758, 11081.889181108085, 11332.71668208285, 510082.0, 564761.0, 568673.0, 572150.0, 679106.0, 149231.25384373008, 151786.0, 152349.0, 158306.0, 160105.0, 964.1804466203811, 1007.1238022920106, 1039.5062345385882, 1039.6309310194922, 1040.7606250562412, 17728.46814595564, 17984.010879401758, 18147.45557633273, 18190.727139092658, 18417.447476575726, 57507.0, 59719.40280597138, 59820.40179598148, 59880.70059649674, 60821.0, 81793.59103204445, 82655.0, 83976.0, 85170.57414712886, 85494.57252713696, 5912.787878484814, 7018.473614478832, 7108.893366599608, 7445.516041457288, 8272.669093236203, 11896.940515297369, 12637.87362126367, 12650.493980240688, 12950.0, 13002.284874332003, 9663.371880827724, 9936.857261415029, 10841.0, 11208.0, 11229.55081796719, 10505.0, 10928.726781830517, 11086.667399978014, 11311.943440282746, 11324.6036388726, 9721.173700235558, 10220.437875916896, 11000.339979601249, 11483.368414736935, 11577.594784182513, 6178.783742568989, 6958.686859091252, 7591.772246832604, 8366.958165209135, 8464.280536154496, 10032.297739158188, 10860.131189504978, 11124.499397527292, 11180.608678696206, 11239.831402529133], "value": 9573.165911992905, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.4931976529156, 1411.6188629070152, 1439.0789894467537, 1449.6448370149315, 1450.9056911300768, 6540.0, 7003.299670032994, 7486.550806951573, 7756.650950707218, 8487.023992240895, 7916.0, 7977.960110199456, 8204.876926846102, 8235.835283294331, 8310.626021829032, 2058.619155456238, 2343.7656234376595, 2356.670066190726, 2357.9389274826312, 2358.9764102359013, 1893.9053047347647, 2075.7301550798375, 2350.224425939436, 2352.8353015288935, 2365.396823809926, 2079.918442409946, 2245.438640339915, 2321.442853715105, 2322.6980492535945, 2330.1262026739973, 2230.10795681727, 2235.2400183937457, 2252.209493452701, 2257.413072601119, 2259.1189436119844, 1996.2114964589052, 2309.145616122029, 2330.8484948478294, 2353.7175538935267, 2368.4789346343778, 8160.387970902206, 8368.414211005233, 8460.0, 8472.576371181478, 8525.95737021311, 5056.721880296583, 5173.637845350827, 5193.324867767204, 5201.973990130063, 5205.531502164786, 3506.912857014334, 3527.171114788023, 3550.3254381667402, 3575.8390872410805, 3587.7667951583326, 1217.3304682424719, 1219.548766956223, 1248.5442813373054, 1260.464302671361, 1271.167385362589, 10664.893351066468, 10789.406582637952, 10800.297980631156, 10825.188110891715, 10825.566977320897, 4470.173017991617, 4560.4983451820935, 4714.81140754366, 4730.219513780256, 4747.406574178228, 9403.482808445464, 9470.857937130948, 9497.43015419077, 9549.856752148724, 9593.712188634352, 5234.842954711365, 5236.109861323508, 5237.371515418175, 5253.737313134405, 5257.053730328501, 3984.561698213194, 4098.8360465581045, 4273.337632666941, 4728.88177795551, 4744.051189762062, 3800.2969450651717, 3812.256609961031, 3868.516435445569, 3900.921981560353, 3903.3559462688904, 1180.3685028509879, 1180.6576092932953, 1290.5612091888615, 1293.9741205176033, 1299.2918859221747, 681.7239018197638, 691.1188235000415, 776.0919723923024, 850.6639877248452, 901.4501154295874, 4562.315652652062, 5354.65194762347, 5364.329458817648, 5461.808836690697, 5585.720713964285, 974.2108891797667, 979.8628192053118, 985.7190700650424, 1029.7168278723411, 1036.9066783989476, 3694.3165514379925, 3743.232637309345, 3748.1566647504637, 3777.2634336304704, 3782.3002744492355, 9649.710508684751, 9988.25088118394, 10010.599576017023, 10032.347897386646, 10064.993500649876, 9957.004299569984, 9968.900310996942, 9974.850377244347, 9980.700578982642, 10006.449645269435, 6114.633122012693, 7637.465377423527, 7766.961165194138, 7792.142864285038, 7862.8427431451055, 4175.352820312856, 4912.45962944083, 5270.787718824615, 5612.635178713371, 5617.522510586482, 8929.776755581162, 9210.309226807882, 9222.584983675617, 9386.530673466435, 9393.154616084585, 9321.72034838956, 9337.346385752931, 9418.4819834907, 9442.38624489406, 9584.76038099053, 7124.928750712426, 7239.022731931122, 7255.383292419992, 7264.564126152448, 7272.872704730775, 7697.230276972482, 7889.802754931173, 7889.842203156019, 7894.171112033384, 7900.486468379537, 6071.483923866344, 7068.858622827412, 7167.534110282815, 7223.566586004857, 7285.307895749896, 7029.578225306498, 7062.929370706227, 7067.5052746307265, 7081.858362832818, 7103.360697537247, 5316.6012549058205, 6302.936970630235, 6354.618722876642, 6424.807255782334, 6431.453326467303, 4965.577925876341, 4989.675671081368, 5027.698338099725, 5069.5944324454695, 5103.540681338697, 4901.823562345083, 5019.3976722793495, 5103.0, 5181.35233095863, 5188.118019936544], "value": 4368.041570456975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.95245713143026, 321.7104605854731, 341.4076577138664, 361.0738455860717, 361.26302343219834, 4469.731816091037, 4881.267809828528, 4936.925946110802, 5389.757460914258, 5471.234027236185, 4905.901881962368, 5294.682319060849, 5320.866978325544, 5338.412774594792, 5358.374396545693, 3851.6726078283377, 4215.641670458022, 4226.260404429228, 4227.387028880826, 4230.323148296273, 3594.514740510037, 3861.189150278431, 3968.484097067377, 4027.7381970171846, 4230.788460576974, 3852.2873268445287, 4100.2209580179815, 4138.3999320098565, 4140.7515549067, 4173.269677806387, 3217.3726123405945, 3287.7698561100733, 4092.386142078682, 4159.168166366739, 4162.895927601834, 3113.097201811472, 3683.4290684943876, 3690.704743620504, 3692.833822477995, 3709.4049558689753, 4626.629869610423, 4662.696924699918, 4759.666823322369, 4902.264660300948, 4971.701697898137, 4405.625521830648, 4687.39063921688, 4804.567588917014, 4833.613310935117, 4841.9031619367415, 4955.033768415161, 5036.5718913892, 5348.117560602496, 5454.836354909359, 5663.490285874291, 3371.983140084284, 4015.8354077317545, 4033.7176397652174, 4039.151778126597, 4056.513218413809, 5632.887342253173, 5662.433756624346, 5677.347105082927, 5891.587588868781, 5967.850803729898, 3845.038740314921, 4063.878083657495, 4147.294959856831, 4152.280256432042, 4174.874753757392, 4194.580541945842, 4204.579542045771, 4205.3481710334945, 4212.957870421256, 4229.323308270724, 4085.8763839944254, 4514.954850451453, 4837.588804951548, 4852.59274810301, 4882.292067650171, 5206.349206349207, 5683.959081891168, 5704.3154821421695, 5718.51392631631, 5757.510611598061, 1108.8613923259593, 1270.5870592057618, 1283.5058502476613, 1373.6291201375773, 1391.2139641102744, 759.8822182561602, 911.0843602179767, 943.6697155995347, 956.2350119904049, 958.1651138070517, 3880.1075752577067, 4391.409068649512, 4976.601871850316, 5075.898482030412, 5093.847184584469, 4908.7910440302, 5118.078745825787, 5578.242853501215, 5588.6646801192055, 5618.69097199658, 4454.621357184611, 4696.835610753607, 4721.268203428474, 4879.229917773508, 4917.262410638433, 2519.60946053362, 2530.7595778401023, 2554.8083893708044, 2626.0414948543644, 2781.928957351394, 4819.867331177171, 4858.6356023298395, 5509.724513774296, 5766.933117373215, 5814.56940405163, 4726.480087190406, 4972.950270497248, 5308.522232999048, 5475.342958844964, 5548.9722551386985, 5634.690092044895, 6049.581986177795, 6071.726772295169, 6085.513158947185, 6101.481374083252, 5618.353889302783, 5855.268091488564, 5881.088861013825, 5894.705264736832, 5902.4097590240635, 5043.2939388486375, 5065.898682026413, 5114.5652619527755, 5487.670739755627, 5628.718564071862, 4805.567498925114, 5233.869153271199, 5773.278340207474, 5990.670513121646, 6002.759889604542, 4155.833766649421, 4231.942014496376, 4247.723897946624, 4256.17004684096, 4266.914661706686, 5778.595498315078, 5847.44449277318, 5848.0, 5894.705264736832, 5896.6756828374855, 5298.470152984825, 5600.523955463831, 5612.522935550361, 5632.211490391266, 5640.774369025193, 4472.217361961709, 5094.592432605308, 5634.253381451763, 5661.952538780339, 5703.62926409782, 4419.911601768011, 5130.97434512825, 5404.54061404785, 5434.429384914527, 5516.234804862405, 5018.598512118949, 5215.018293048617, 5635.802746903839, 5730.914036289542, 5739.253896993364, 5240.0087966572455, 5558.415336868376, 5561.416051314553, 5575.721213939368, 5611.0], "value": 4099.518181233395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.9541532092752, 688.8071340024795, 693.7883945396655, 702.181958018908, 769.4344656677341, 12540.310282934435, 14252.928735356321, 14714.926425367872, 14878.479253226125, 15464.0, 14180.787288190659, 14315.997880148394, 14362.0, 15010.624734381647, 15500.302486388111, 862.1723145780059, 873.2359185712502, 881.0528681667201, 882.9735107946772, 885.6944354197791, 672.2235817630644, 691.9931499667971, 844.6156998565668, 867.9001914779786, 871.1984973824098, 911.9498427586481, 918.2975024106564, 919.6183583812709, 920.6225447566499, 923.1138107416855, 911.0570559470982, 911.9772005699845, 914.3462424366577, 915.844306467902, 917.9127982841619, 677.7356830835978, 677.9525433219677, 678.8676208139415, 678.8947713104477, 679.0357692077262, 14750.852491475056, 15181.620459488491, 15581.298841552158, 15685.764713529306, 15764.921175394164, 13767.242801645905, 14150.646233844132, 14171.716565668628, 14205.78691319631, 14246.786298205536, 11604.767904641943, 11733.12001599875, 11799.292042477478, 11816.704582385422, 12111.212771169845, 3674.6141655126094, 3685.981570092159, 3690.4279836625365, 3744.794036328001, 3834.7699138051526, 94953.0504694958, 106394.46802765937, 108497.0, 109121.0, 110615.0, 8981.685641002534, 10263.640772572924, 10686.625968091079, 10803.919608039134, 10847.837282440769, 21720.674189887166, 22087.889560552416, 22137.0, 22172.445688857584, 22203.333899983027, 6518.44593209573, 7582.431317651198, 7656.5791756512035, 7766.9611651942505, 7773.727919522789, 4149.605787450188, 4233.724807887477, 4246.341817018367, 4365.105153443536, 4448.399466072104, 3525.471179323122, 3639.3813051781262, 3643.544556930384, 3645.179834537261, 3649.0694872807794, 536.9731513424313, 560.814931072745, 656.6191608866845, 666.3469799596422, 675.1358261425383, 571.4172140677927, 660.6927778582982, 679.6160169504215, 683.1426559667684, 693.2166651683666, 9946.602135914627, 10199.796004079877, 10293.691189264335, 10357.844632330522, 10468.110210632181, 601.9157317975487, 639.9040143978348, 654.3521913305793, 662.6057495790094, 667.6661669165418, 4110.46563946691, 4911.189653707098, 4956.628252881048, 5003.4746351633285, 5028.522290382481, 106384.0, 107264.0, 107483.46258268658, 108562.45718771356, 108884.4555777216, 90282.0, 95346.09307813944, 96317.0, 97587.58443454445, 97947.51026244824, 55392.0, 82400.0, 82400.58799705963, 82872.58563707264, 85375.99999999876, 9419.0, 9658.517074146404, 9662.178714809039, 9683.370580912218, 9713.660021899199, 52577.0, 53315.73342133265, 53371.932561349335, 54576.454235458725, 55709.885802282915, 64623.0, 69787.6510617464, 70428.0, 70615.0, 70875.64562177156, 11703.2978021319, 11742.530298787953, 11747.706307342385, 11772.17594768358, 12007.339596322287, 12575.182613130117, 12717.300548469553, 12742.362881856056, 12870.485180593047, 13025.348732563523, 9653.613855445703, 12183.0, 12207.572734954234, 12301.384930753606, 12597.307148106942, 11783.705407364541, 12014.098942579516, 12272.44773985155, 12282.508699651913, 12552.058595605184, 11682.649520514398, 12035.578754743892, 12386.31875246834, 12404.19372740769, 12404.503819847467, 8079.828424878478, 9729.756756081155, 9901.257405694458, 9976.850347244943, 10170.949145254226, 6965.746165690224, 9088.681896133603, 9311.067013890603, 9785.755356116155, 9924.214905231289], "value": 7320.99136545061, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 117.0, 117.0, 118.0, 113.0, 115.0, 118.0, 117.0, 118.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 103.0, 97.0, 129.0, 145.0, 145.0, 144.0, 111.0, 129.0, 110.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 273.0, 214.0, 212.0, 203.0, 197.0, 196.0, 197.0, 233.0, 244.0], "value": 218.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 51.0, 61.0, 77.0, 71.0, 71.0, 54.0, 54.0, 78.0, 84.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 83.0, 72.0, 73.0, 70.0, 81.0, 70.0, 77.0, 96.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 92.0, 88.0, 90.0, 73.0, 137.0, 133.0, 99.0, 86.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 94.0, 139.0, 120.0, 118.0, 108.0, 153.0, 153.0, 113.0], "value": 119.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 125.0, 93.0, 92.0, 92.0, 142.0, 94.0, 90.0, 93.0, 92.0], "value": 101.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 63.0, 62.0, 61.0, 65.0, 73.0, 94.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 72.0, 68.0, 90.0, 92.0, 105.0, 106.0, 59.0, 98.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 117.0, 79.0, 97.0, 108.0, 109.0, 112.0, 83.0, 104.0, 111.0], "value": 103.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 146.0, 142.0, 134.0, 154.0, 157.0, 161.0, 155.0, 192.0, 162.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 176.0, 175.0, 176.0, 176.0, 176.0, 175.0, 177.0, 177.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 85.0, 83.0, 78.0, 69.0, 69.0, 71.0, 83.0, 71.0, 62.0], "value": 74.2, "unit": "score"}]}]} 10:07:33 INFO - Return code: 0 10:07:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:33 ERROR - # TBPL SUCCESS # 10:07:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:33 INFO - 2017-01-07 10:07:33.342 screenresolution[1921:20136] starting screenresolution argv=screenresolution get 10:07:33 INFO - 2017-01-07 10:07:33.345 screenresolution[1921:20136] Display 0: 1600x1200x32@60 10:07:33 INFO - 2017-01-07 10:07:33.352 screenresolution[1922:20139] starting screenresolution argv=screenresolution list 10:07:33 INFO - Available Modes on Display 0 10:07:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:33 INFO - Intel Iris: 10:07:33 INFO - Chipset Model: Intel Iris 10:07:33 INFO - Type: GPU 10:07:33 INFO - Bus: Built-In 10:07:33 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:33 INFO - Vendor: Intel (0x8086) 10:07:33 INFO - Device ID: 0x0a2e 10:07:33 INFO - Revision ID: 0x0009 10:07:33 INFO - Displays: 10:07:33 INFO - Display: 10:07:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:33 INFO - Display Serial Number: 1600x1200 60 10:07:33 INFO - Main Display: Yes 10:07:33 INFO - Mirror: Off 10:07:33 INFO - Online: Yes 10:07:33 INFO - Rotation: Supported 10:07:33 INFO - Return code: 0 10:07:33 INFO - Running post-action listener: _resource_record_post_action 10:07:33 INFO - [mozharness: 2017-01-07 18:07:33.590601Z] Finished run-tests step (success) 10:07:33 INFO - Running post-run listener: _resource_record_post_run 10:07:33 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 385024; Write bytes: 552984576; Read time: 4; Write time: 1712 10:07:33 INFO - TinderboxPrint: CPU usage
54.5% 10:07:33 INFO - TinderboxPrint: I/O read bytes / time
385,024 / 4 10:07:33 INFO - TinderboxPrint: I/O write bytes / time
552,984,576 / 1,712 10:07:33 INFO - TinderboxPrint: CPU idle
2,186.2 (45.5%) 10:07:33 INFO - TinderboxPrint: CPU system
964.8 (20.1%) 10:07:33 INFO - TinderboxPrint: CPU user
1,650.8 (34.4%) 10:07:33 INFO - TinderboxPrint: Swap in / out
249,556,992 / 0 10:07:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226765312; Write bytes: 223704576; Read time: 16403; Write time: 493 10:07:33 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 385024; Write bytes: 299743744; Read time: 4; Write time: 1151 10:07:34 INFO - Running post-run listener: _upload_blobber_files 10:07:34 INFO - Blob upload gear active. 10:07:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:34 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 10:07:34 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'] 10:07:34 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 10:07:34 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:35 INFO - (blobuploader) - INFO - Done attempting. 10:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:36 INFO - (blobuploader) - INFO - Done attempting. 10:07:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:07:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:37 INFO - (blobuploader) - INFO - Done attempting. 10:07:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:39 INFO - (blobuploader) - INFO - Done attempting. 10:07:39 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:39 INFO - Return code: 0 10:07:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0004de77188e41ba717a017e805497e1e99684af7bed1a0ce5da8ddedd39727378e7ba55c85801dff47159986d1ba8cf3e874790f20c845613c0dd305449fc42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b881839c6ff310197b60cd0ae1f6f80e1488e072c45b48ddfbaab336fbffa451ad7a318a8623a2617f2217aeea153774b4d3196ecbf2166ee0089c8f595a8f60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b02c9b2ba092916e11341c06883f6763b8f4f5ae60751f616b693e05083b6911a849a3222df67e115b31480239142ecf332fb84674d25e1f979b3aff9186982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d432e3bd172ff1d061f4d7bc976fdcd63aa3491b4fbdcf7830396e2e96a06e91f3617c6dda03b90fc9fe1f203bd655e8d887df836eff65aeb518314be7acd36a"} 10:07:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:39 INFO - Contents: 10:07:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0004de77188e41ba717a017e805497e1e99684af7bed1a0ce5da8ddedd39727378e7ba55c85801dff47159986d1ba8cf3e874790f20c845613c0dd305449fc42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b881839c6ff310197b60cd0ae1f6f80e1488e072c45b48ddfbaab336fbffa451ad7a318a8623a2617f2217aeea153774b4d3196ecbf2166ee0089c8f595a8f60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b02c9b2ba092916e11341c06883f6763b8f4f5ae60751f616b693e05083b6911a849a3222df67e115b31480239142ecf332fb84674d25e1f979b3aff9186982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d432e3bd172ff1d061f4d7bc976fdcd63aa3491b4fbdcf7830396e2e96a06e91f3617c6dda03b90fc9fe1f203bd655e8d887df836eff65aeb518314be7acd36a"} 10:07:39 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:39 INFO - Copying logs to upload dir... 10:07:39 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.085658 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-07 10:07:39.441367) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 10:07:39.445176) ========= 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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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/0004de77188e41ba717a017e805497e1e99684af7bed1a0ce5da8ddedd39727378e7ba55c85801dff47159986d1ba8cf3e874790f20c845613c0dd305449fc42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b881839c6ff310197b60cd0ae1f6f80e1488e072c45b48ddfbaab336fbffa451ad7a318a8623a2617f2217aeea153774b4d3196ecbf2166ee0089c8f595a8f60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b02c9b2ba092916e11341c06883f6763b8f4f5ae60751f616b693e05083b6911a849a3222df67e115b31480239142ecf332fb84674d25e1f979b3aff9186982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d432e3bd172ff1d061f4d7bc976fdcd63aa3491b4fbdcf7830396e2e96a06e91f3617c6dda03b90fc9fe1f203bd655e8d887df836eff65aeb518314be7acd36a"} build_url:https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010996 build_url: 'https://queue.taskcluster.net/v1/task/L35yll8EQlGQ2ifHcVx3vA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0004de77188e41ba717a017e805497e1e99684af7bed1a0ce5da8ddedd39727378e7ba55c85801dff47159986d1ba8cf3e874790f20c845613c0dd305449fc42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b881839c6ff310197b60cd0ae1f6f80e1488e072c45b48ddfbaab336fbffa451ad7a318a8623a2617f2217aeea153774b4d3196ecbf2166ee0089c8f595a8f60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b02c9b2ba092916e11341c06883f6763b8f4f5ae60751f616b693e05083b6911a849a3222df67e115b31480239142ecf332fb84674d25e1f979b3aff9186982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d432e3bd172ff1d061f4d7bc976fdcd63aa3491b4fbdcf7830396e2e96a06e91f3617c6dda03b90fc9fe1f203bd655e8d887df836eff65aeb518314be7acd36a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 10:07:39.479332) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 10:07:39.479627) ========= 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.4hLRRfxhyk/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.C8AoWYsPMj/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.005087 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 10:07:39.531624) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 10:07:39.531942) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 10:07:39.532251) ========= ========= Total master_lag: 0.21 =========