builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1482972276.01 results: success (0) buildid: 20161228160136 builduid: 2cac69f328644f2b8a6ebff0882aeaf2 revision: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.012891) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.013363) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.013730) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.038946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.039381) ========= 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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 --2016-12-28 16:44:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.93M=0.001s 2016-12-28 16:44:36 (7.93 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.492813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.556312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.556619) ========= 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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.019586 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:36.624643) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 16:44:36.625011) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b882b98ee9ddba735a0a3a57623e0b9d9fc7742a --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 b882b98ee9ddba735a0a3a57623e0b9d9fc7742a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HxivbWiuSp/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.e9C2JIkgdZ/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 2016-12-28 16:44:36,697 truncating revision to first 12 chars 2016-12-28 16:44:36,697 Setting DEBUG logging. 2016-12-28 16:44:36,697 attempt 1/10 2016-12-28 16:44:36,697 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b882b98ee9dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 16:44:38,313 unpacking tar archive at: mozilla-central-b882b98ee9dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.872592 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 16:44:38.521627) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:38.521944) ========= script_repo_revision: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:38.522332) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:38.522615) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:38.545952) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-28 16:44:38.546294) ========= /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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:44:38 INFO - MultiFileLogger online at 20161228 16:44:38 in /builds/slave/test 16:44: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 16:44:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:44:38 INFO - {'append_to_log': False, 16:44:38 INFO - 'base_work_dir': '/builds/slave/test', 16:44:38 INFO - 'blob_upload_branch': 'Firefox', 16:44:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:44:38 INFO - 'branch': 'Firefox', 16:44:38 INFO - 'buildbot_json_path': 'buildprops.json', 16:44:38 INFO - 'config_files': ('talos/mac_config.py',), 16:44:38 INFO - 'default_actions': ('clobber', 16:44:38 INFO - 'read-buildbot-config', 16:44:38 INFO - 'download-and-extract', 16:44:38 INFO - 'populate-webroot', 16:44:38 INFO - 'create-virtualenv', 16:44:38 INFO - 'install', 16:44:38 INFO - 'run-tests'), 16:44:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:44:38 INFO - 'download_minidump_stackwalk': True, 16:44:38 INFO - 'download_symbols': 'ondemand', 16:44:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:44:38 INFO - 'tooltool.py': '/tools/tooltool.py', 16:44:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:44:38 INFO - '/tools/misc-python/virtualenv.py')}, 16:44:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:44:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:44:38 INFO - 'installer_path': 'installer.exe', 16:44:38 INFO - 'log_level': 'info', 16:44:38 INFO - 'log_name': 'talos', 16:44:38 INFO - 'log_to_console': True, 16:44:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:44:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:44:38 INFO - 'opt_config_files': (), 16:44:38 INFO - 'pip_index': False, 16:44:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:38 INFO - 'cmd': ('bash', 16:44:38 INFO - '-c', 16:44:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:38 INFO - 'enabled': True, 16:44:38 INFO - 'halt_on_failure': False, 16:44:38 INFO - 'name': 'check_screen_resolution'},), 16:44:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:38 INFO - 'cmd': ('bash', 16:44:38 INFO - '-c', 16:44:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:38 INFO - 'enabled': True, 16:44:38 INFO - 'halt_on_failure': False, 16:44:38 INFO - 'name': 'check_screen_resolution'},), 16:44:38 INFO - 'run_cmd_checks_enabled': True, 16:44:38 INFO - 'sps_profile': False, 16:44:38 INFO - 'sps_profile_interval': 0, 16:44:38 INFO - 'suite': 'dromaeojs-e10s', 16:44:38 INFO - 'system_bits': '32', 16:44:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:44:38 INFO - 'title': 't-yosemite-r7-0200', 16:44:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:44:38 INFO - 'use_talos_json': True, 16:44:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:44:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:44:38 INFO - 'work_dir': 'build'} 16:44:38 INFO - [mozharness: 2016-12-29 00:44:38.743065Z] Running clobber step. 16:44:38 INFO - Running pre-action listener: _resource_record_pre_action 16:44:38 INFO - Running main action method: clobber 16:44:38 INFO - rmtree: /builds/slave/test/build 16:44:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:44:40 INFO - Running post-action listener: _resource_record_post_action 16:44:40 INFO - [mozharness: 2016-12-29 00:44:40.976439Z] Finished clobber step (success) 16:44:40 INFO - [mozharness: 2016-12-29 00:44:40.976622Z] Running read-buildbot-config step. 16:44:40 INFO - Running pre-action listener: _resource_record_pre_action 16:44:40 INFO - Running main action method: read_buildbot_config 16:44:40 INFO - Using buildbot properties: 16:44:40 INFO - { 16:44:40 INFO - "project": "", 16:44:40 INFO - "product": "firefox", 16:44:40 INFO - "script_repo_revision": "production", 16:44:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:44:40 INFO - "repository": "", 16:44:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:44:40 INFO - "buildid": "20161228160136", 16:44:40 INFO - "pgo_build": "False", 16:44:40 INFO - "basedir": "/builds/slave/test", 16:44:40 INFO - "buildnumber": 115, 16:44:40 INFO - "slavename": "t-yosemite-r7-0200", 16:44:40 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:44:40 INFO - "platform": "yosemite_r7", 16:44:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:44:40 INFO - "branch": "mozilla-central", 16:44:40 INFO - "repo_path": "mozilla-central", 16:44:40 INFO - "revision": "b882b98ee9ddba735a0a3a57623e0b9d9fc7742a", 16:44:40 INFO - "stage_platform": "macosx64", 16:44:40 INFO - "builduid": "2cac69f328644f2b8a6ebff0882aeaf2", 16:44:40 INFO - "slavebuilddir": "test" 16:44:40 INFO - } 16:44:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 16:44:40 INFO - Running post-action listener: _resource_record_post_action 16:44:40 INFO - [mozharness: 2016-12-29 00:44:40.980473Z] Finished read-buildbot-config step (success) 16:44:40 INFO - [mozharness: 2016-12-29 00:44:40.980611Z] Running download-and-extract step. 16:44:40 INFO - Running pre-action listener: _resource_record_pre_action 16:44:40 INFO - Running main action method: download_and_extract 16:44:40 INFO - mkdir: /builds/slave/test/build/tests 16:44: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')]} 16:44:40 INFO - trying https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 16:44:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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 16:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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 16:44:42 INFO - Downloaded 1199 bytes. 16:44:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 16:44:42 INFO - Using the following test package requirements: 16:44:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:44:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 16:44:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 16:44:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'jsshell-mac.zip'], 16:44:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 16:44:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:44:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 16:44:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 16:44:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 16:44:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 16:44:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:44:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:44:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:44:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:44:43 INFO - Expected file size: 16295323 16:44:43 INFO - Obtained file size: 16295323 16:44: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 16:44:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:44:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:44:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:44:51 INFO - Expected file size: 16295323 16:44:51 INFO - Obtained file size: 16295323 16:44:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 16:44:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:44:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 16:44:59 INFO - Expected file size: 14409776 16:44:59 INFO - Obtained file size: 14409776 16:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:59 INFO - trying https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:44:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 16:44:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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 16:45:02 INFO - Downloaded 69629180 bytes. 16:45:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:45:02 INFO - mkdir: /builds/slave/test/properties 16:45:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:45:02 INFO - Writing to file /builds/slave/test/properties/build_url 16:45:02 INFO - Contents: 16:45:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:45:03 INFO - Running post-action listener: _resource_record_post_action 16:45:03 INFO - Running post-action listener: set_extra_try_arguments 16:45:03 INFO - [mozharness: 2016-12-29 00:45:03.544271Z] Finished download-and-extract step (success) 16:45:03 INFO - [mozharness: 2016-12-29 00:45:03.544441Z] Running populate-webroot step. 16:45:03 INFO - Running pre-action listener: _resource_record_pre_action 16:45:03 INFO - Running main action method: populate_webroot 16:45:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:45:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:45:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:45:03 INFO - '--tppagecycles', 16:45:03 INFO - '7'], 16:45:03 INFO - 'tests': ['tsvgm']}}, 16:45:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:45:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:45:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:45:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:45:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:45:03 INFO - 'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:45:03 INFO - 'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['damp', 'tps']}, 16:45:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:45:03 INFO - 'tests': ['damp', 'tps']}, 16:45:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['dromaeo_dom']}, 16:45:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:45:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['basic_compositor_video']}, 16:45:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:45:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['a11yr', 16:45:03 INFO - 'ts_paint', 16:45:03 INFO - 'tpaint', 16:45:03 INFO - 'sessionrestore', 16:45:03 INFO - 'sessionrestore_no_auto_restore', 16:45:03 INFO - 'tabpaint']}, 16:45:03 INFO - 'other-e10s': {'tests': ['a11yr', 16:45:03 INFO - 'ts_paint', 16:45:03 INFO - 'tpaint', 16:45:03 INFO - 'sessionrestore', 16:45:03 INFO - 'sessionrestore_no_auto_restore', 16:45:03 INFO - 'tabpaint']}, 16:45:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:45:03 INFO - 'ts_paint', 16:45:03 INFO - 'tpaint', 16:45:03 INFO - 'sessionrestore', 16:45:03 INFO - 'sessionrestore_no_auto_restore', 16:45:03 INFO - 'tabpaint']}, 16:45:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:45:03 INFO - 'ts_paint', 16:45:03 INFO - 'tpaint', 16:45:03 INFO - 'sessionrestore', 16:45:03 INFO - 'sessionrestore_no_auto_restore', 16:45:03 INFO - 'tabpaint']}, 16:45:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['a11yr', 16:45:03 INFO - 'ts_paint', 16:45:03 INFO - 'tpaint', 16:45:03 INFO - 'sessionrestore', 16:45:03 INFO - 'sessionrestore_no_auto_restore', 16:45:03 INFO - 'tabpaint']}, 16:45:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['a11yr', 16:45:03 INFO - 'ts_paint', 16:45:03 INFO - 'tpaint', 16:45:03 INFO - 'sessionrestore', 16:45:03 INFO - 'sessionrestore_no_auto_restore', 16:45:03 INFO - 'tabpaint']}, 16:45:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['tsvgx', 16:45:03 INFO - 'tsvgr_opacity', 16:45:03 INFO - 'tart', 16:45:03 INFO - 'tscrollx', 16:45:03 INFO - 'cart', 16:45:03 INFO - 'tsvg_static']}, 16:45:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:45:03 INFO - 'tsvgr_opacity', 16:45:03 INFO - 'tart', 16:45:03 INFO - 'tscrollx', 16:45:03 INFO - 'cart', 16:45:03 INFO - 'tsvg_static']}, 16:45:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:45:03 INFO - 'talos_options': ['--disable-e10s'], 16:45:03 INFO - 'tests': ['tp5o']}, 16:45:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:45:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:45:03 INFO - 'talos_options': ['--disable-e10s', 16:45:03 INFO - '--xperf_path', 16:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:03 INFO - 'tests': ['tp5n']}, 16:45:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:45:03 INFO - 'talos_options': ['--xperf_path', 16:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:03 INFO - 'tests': ['tp5n']}}, 16:45:03 INFO - 'talos.zip': {'path': '', 16:45:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:45:03 INFO - Running post-action listener: _resource_record_post_action 16:45:03 INFO - [mozharness: 2016-12-29 00:45:03.563388Z] Finished populate-webroot step (success) 16:45:03 INFO - [mozharness: 2016-12-29 00:45:03.563519Z] Running create-virtualenv step. 16:45:03 INFO - Running pre-action listener: _resource_record_pre_action 16:45:03 INFO - Running main action method: create_virtualenv 16:45:03 INFO - Creating virtualenv /builds/slave/test/build/venv 16:45:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:45:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:45:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:45:03 INFO - Using real prefix '/tools/python27' 16:45:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:45:04 INFO - Installing distribute.............................................................................................................................................................................................done. 16:45:07 INFO - Installing pip.................done. 16:45:07 INFO - Return code: 0 16:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:45:07 INFO - Reading from file tmpfile_stdout 16:45:07 INFO - Output received: 16:45:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:45:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:07 INFO - 'HOME': '/Users/cltbld', 16:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:07 INFO - 'LOGNAME': 'cltbld', 16:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:07 INFO - 'MOZ_NO_REMOTE': '1', 16:45:07 INFO - 'NO_EM_RESTART': '1', 16:45:07 INFO - 'PAGER': '/bin/cat', 16:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:07 INFO - 'PWD': '/builds/slave/test', 16:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:07 INFO - 'SHELL': '/bin/bash', 16:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:07 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:07 INFO - 'USER': 'cltbld', 16:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:07 INFO - 'XPC_FLAGS': '0x0', 16:45:07 INFO - 'XPC_SERVICE_NAME': '0', 16:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:45:07 INFO - Cleaning up... 16:45:07 INFO - Return code: 0 16:45:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:07 INFO - 'HOME': '/Users/cltbld', 16:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:07 INFO - 'LOGNAME': 'cltbld', 16:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:07 INFO - 'MOZ_NO_REMOTE': '1', 16:45:07 INFO - 'NO_EM_RESTART': '1', 16:45:07 INFO - 'PAGER': '/bin/cat', 16:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:07 INFO - 'PWD': '/builds/slave/test', 16:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:07 INFO - 'SHELL': '/bin/bash', 16:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:07 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:07 INFO - 'USER': 'cltbld', 16:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:07 INFO - 'XPC_FLAGS': '0x0', 16:45:07 INFO - 'XPC_SERVICE_NAME': '0', 16:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:08 INFO - Downloading/unpacking psutil>=3.1.1 16:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:45:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:12 INFO - Installing collected packages: psutil 16:45:12 INFO - Running setup.py install for psutil 16:45:12 INFO - building 'psutil._psutil_osx' extension 16:45:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:45:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:45:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:45:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:45:12 INFO - building 'psutil._psutil_posix' extension 16:45:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:45:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:45:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:45:12 INFO - ^ 16:45:12 INFO - 1 warning generated. 16:45:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:45:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:13 INFO - Successfully installed psutil 16:45:13 INFO - Cleaning up... 16:45:13 INFO - Return code: 0 16:45:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:45: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')]} 16:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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')]} 16:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:45: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 mozsystemmonitor==0.3 16:45:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:13 INFO - 'HOME': '/Users/cltbld', 16:45:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:13 INFO - 'LOGNAME': 'cltbld', 16:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:13 INFO - 'MOZ_NO_REMOTE': '1', 16:45:13 INFO - 'NO_EM_RESTART': '1', 16:45:13 INFO - 'PAGER': '/bin/cat', 16:45:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:13 INFO - 'PWD': '/builds/slave/test', 16:45:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:13 INFO - 'SHELL': '/bin/bash', 16:45:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:13 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:13 INFO - 'USER': 'cltbld', 16:45:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:13 INFO - 'XPC_FLAGS': '0x0', 16:45:13 INFO - 'XPC_SERVICE_NAME': '0', 16:45:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:45:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:45:16 INFO - Installing collected packages: mozsystemmonitor 16:45:16 INFO - Running setup.py install for mozsystemmonitor 16:45:16 INFO - Successfully installed mozsystemmonitor 16:45:16 INFO - Cleaning up... 16:45:16 INFO - Return code: 0 16:45:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:45: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')]} 16:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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')]} 16:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:16 INFO - 'HOME': '/Users/cltbld', 16:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:16 INFO - 'LOGNAME': 'cltbld', 16:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:16 INFO - 'MOZ_NO_REMOTE': '1', 16:45:16 INFO - 'NO_EM_RESTART': '1', 16:45:16 INFO - 'PAGER': '/bin/cat', 16:45:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:16 INFO - 'PWD': '/builds/slave/test', 16:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:16 INFO - 'SHELL': '/bin/bash', 16:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:16 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:16 INFO - 'USER': 'cltbld', 16:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:16 INFO - 'XPC_FLAGS': '0x0', 16:45:16 INFO - 'XPC_SERVICE_NAME': '0', 16:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:16 INFO - Downloading/unpacking jsonschema==2.5.1 16:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:45:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45:20 INFO - Downloading functools32-3.2.3-2.tar.gz 16:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:45:20 INFO - warning: no files found matching '*.txt' 16:45:20 INFO - no previously-included directories found matching 'build' 16:45:20 INFO - no previously-included directories found matching 'dist' 16:45:20 INFO - no previously-included directories found matching '.git*' 16:45:20 INFO - Installing collected packages: jsonschema, functools32 16:45:20 INFO - Running setup.py install for functools32 16:45:20 INFO - warning: no files found matching '*.txt' 16:45:20 INFO - no previously-included directories found matching 'build' 16:45:20 INFO - no previously-included directories found matching 'dist' 16:45:20 INFO - no previously-included directories found matching '.git*' 16:45:20 INFO - Successfully installed jsonschema functools32 16:45:20 INFO - Cleaning up... 16:45:20 INFO - Return code: 0 16:45:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:21 INFO - 'HOME': '/Users/cltbld', 16:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:21 INFO - 'LOGNAME': 'cltbld', 16:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:21 INFO - 'MOZ_NO_REMOTE': '1', 16:45:21 INFO - 'NO_EM_RESTART': '1', 16:45:21 INFO - 'PAGER': '/bin/cat', 16:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:21 INFO - 'PWD': '/builds/slave/test', 16:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:21 INFO - 'SHELL': '/bin/bash', 16:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:21 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:21 INFO - 'USER': 'cltbld', 16:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:21 INFO - 'XPC_FLAGS': '0x0', 16:45:21 INFO - 'XPC_SERVICE_NAME': '0', 16:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:45:21 INFO - Cleaning up... 16:45:21 INFO - Return code: 0 16:45:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:21 INFO - 'HOME': '/Users/cltbld', 16:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:21 INFO - 'LOGNAME': 'cltbld', 16:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:21 INFO - 'MOZ_NO_REMOTE': '1', 16:45:21 INFO - 'NO_EM_RESTART': '1', 16:45:21 INFO - 'PAGER': '/bin/cat', 16:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:21 INFO - 'PWD': '/builds/slave/test', 16:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:21 INFO - 'SHELL': '/bin/bash', 16:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:21 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:21 INFO - 'USER': 'cltbld', 16:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:21 INFO - 'XPC_FLAGS': '0x0', 16:45:21 INFO - 'XPC_SERVICE_NAME': '0', 16:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:21 INFO - Downloading/unpacking blobuploader==1.2.4 16:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:45:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:45:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:45:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:25 INFO - Downloading docopt-0.6.1.tar.gz 16:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:45:26 INFO - Installing collected packages: blobuploader, requests, docopt 16:45:26 INFO - Running setup.py install for blobuploader 16:45:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:45:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:45:26 INFO - Running setup.py install for requests 16:45:26 INFO - Running setup.py install for docopt 16:45:26 INFO - Successfully installed blobuploader requests docopt 16:45:26 INFO - Cleaning up... 16:45:26 INFO - Return code: 0 16:45:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45: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')]} 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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')]} 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:26 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 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:26 INFO - 'HOME': '/Users/cltbld', 16:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:26 INFO - 'LOGNAME': 'cltbld', 16:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:26 INFO - 'MOZ_NO_REMOTE': '1', 16:45:26 INFO - 'NO_EM_RESTART': '1', 16:45:26 INFO - 'PAGER': '/bin/cat', 16:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:26 INFO - 'PWD': '/builds/slave/test', 16:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:26 INFO - 'SHELL': '/bin/bash', 16:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:26 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:26 INFO - 'USER': 'cltbld', 16:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:26 INFO - 'XPC_FLAGS': '0x0', 16:45:26 INFO - 'XPC_SERVICE_NAME': '0', 16:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-2jb3M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Jaim1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-C7AsZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-i5Xi1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-uAKXBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Yn5Efs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-i5VhGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-8gCwsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-rlb2sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-dtVhOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-17oJZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-AVf45J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-yNzHik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-OJ_26v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-bX8khw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-PvRi8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-XsQger-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:45:28 INFO - Running setup.py install for manifestparser 16:45:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozcrash 16:45:29 INFO - Running setup.py install for mozdebug 16:45:29 INFO - Running setup.py install for mozdevice 16:45:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozfile 16:45:29 INFO - Running setup.py install for mozhttpd 16:45:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozinfo 16:45:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozInstall 16:45:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Running setup.py install for mozleak 16:45:30 INFO - Running setup.py install for mozlog 16:45:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Running setup.py install for moznetwork 16:45:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Running setup.py install for mozprocess 16:45:30 INFO - Running setup.py install for mozprofile 16:45:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Running setup.py install for mozrunner 16:45:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:45:31 INFO - Running setup.py install for mozscreenshot 16:45:31 INFO - Running setup.py install for moztest 16:45:31 INFO - Running setup.py install for mozversion 16:45:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:45:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:45:31 INFO - Cleaning up... 16:45:31 INFO - Return code: 0 16:45:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:31 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 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:31 INFO - 'HOME': '/Users/cltbld', 16:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:31 INFO - 'LOGNAME': 'cltbld', 16:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:31 INFO - 'MOZ_NO_REMOTE': '1', 16:45:31 INFO - 'NO_EM_RESTART': '1', 16:45:31 INFO - 'PAGER': '/bin/cat', 16:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:31 INFO - 'PWD': '/builds/slave/test', 16:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:31 INFO - 'SHELL': '/bin/bash', 16:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:31 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:31 INFO - 'USER': 'cltbld', 16:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:31 INFO - 'XPC_FLAGS': '0x0', 16:45:31 INFO - 'XPC_SERVICE_NAME': '0', 16:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-jUeo_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-b5tT05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-TRdtGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-FWD1ri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Y_TShM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-pQtNB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-VgSjxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-F632Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Huw_8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-r0CAlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-BnkUfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-7yrMfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-JLHO1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-N6nkTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-CFqPqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-pDWEUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-1hZylL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:45:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:45:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:36 INFO - Downloading blessings-1.6.tar.gz 16:45:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:45:36 INFO - Installing collected packages: blessings 16:45:36 INFO - Running setup.py install for blessings 16:45:36 INFO - Successfully installed blessings 16:45:36 INFO - Cleaning up... 16:45:36 INFO - Return code: 0 16:45:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:36 INFO - Reading from file tmpfile_stdout 16:45:36 INFO - Current package versions: 16:45:36 INFO - blessings == 1.6 16:45:36 INFO - blobuploader == 1.2.4 16:45:36 INFO - docopt == 0.6.1 16:45:36 INFO - functools32 == 3.2.3-2 16:45:36 INFO - jsonschema == 2.5.1 16:45:36 INFO - manifestparser == 1.1 16:45:36 INFO - mozInstall == 1.12 16:45:36 INFO - mozcrash == 1.0 16:45:36 INFO - mozdebug == 0.1 16:45:36 INFO - mozdevice == 0.49 16:45:36 INFO - mozfile == 1.2 16:45:36 INFO - mozhttpd == 0.7 16:45:36 INFO - mozinfo == 0.9 16:45:36 INFO - mozleak == 0.1 16:45:36 INFO - mozlog == 3.4 16:45:36 INFO - moznetwork == 0.27 16:45:36 INFO - mozprocess == 0.23 16:45:36 INFO - mozprofile == 0.28 16:45:36 INFO - mozrunner == 6.13 16:45:36 INFO - mozscreenshot == 0.1 16:45:36 INFO - mozsystemmonitor == 0.3 16:45:36 INFO - moztest == 0.7 16:45:36 INFO - mozversion == 1.4 16:45:36 INFO - psutil == 3.1.1 16:45:36 INFO - requests == 1.2.3 16:45:36 INFO - wsgiref == 0.1.2 16:45:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:36 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 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:36 INFO - 'HOME': '/Users/cltbld', 16:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:36 INFO - 'LOGNAME': 'cltbld', 16:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:36 INFO - 'MOZ_NO_REMOTE': '1', 16:45:36 INFO - 'NO_EM_RESTART': '1', 16:45:36 INFO - 'PAGER': '/bin/cat', 16:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:36 INFO - 'PWD': '/builds/slave/test', 16:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:36 INFO - 'SHELL': '/bin/bash', 16:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:36 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:36 INFO - 'USER': 'cltbld', 16:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:36 INFO - 'XPC_FLAGS': '0x0', 16:45:36 INFO - 'XPC_SERVICE_NAME': '0', 16:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:45:37 INFO - Cleaning up... 16:45:37 INFO - Return code: 0 16:45:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:37 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 0x1013bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd638c82380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, '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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:37 INFO - 'HOME': '/Users/cltbld', 16:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:37 INFO - 'LOGNAME': 'cltbld', 16:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:37 INFO - 'MOZ_NO_REMOTE': '1', 16:45:37 INFO - 'NO_EM_RESTART': '1', 16:45:37 INFO - 'PAGER': '/bin/cat', 16:45:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:37 INFO - 'PWD': '/builds/slave/test', 16:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:37 INFO - 'SHELL': '/bin/bash', 16:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:37 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:37 INFO - 'USER': 'cltbld', 16:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:37 INFO - 'XPC_FLAGS': '0x0', 16:45:37 INFO - 'XPC_SERVICE_NAME': '0', 16:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:45:37 INFO - Cleaning up... 16:45:37 INFO - Return code: 0 16:45:37 INFO - Running post-action listener: _resource_record_post_action 16:45:37 INFO - Running post-action listener: _start_resource_monitoring 16:45:37 INFO - Starting resource monitoring. 16:45:37 INFO - [mozharness: 2016-12-29 00:45:37.365588Z] Finished create-virtualenv step (success) 16:45:37 INFO - [mozharness: 2016-12-29 00:45:37.366529Z] Running install step. 16:45:37 INFO - Running pre-action listener: _resource_record_pre_action 16:45:37 INFO - Running main action method: install 16:45:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:37 INFO - Reading from file tmpfile_stdout 16:45:37 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:37 INFO - Reading from file tmpfile_stdout 16:45:37 INFO - Output received: 16:45:37 INFO - Usage: mozinstall [options] installer 16:45:37 INFO - Options: 16:45:37 INFO - -h, --help show this help message and exit 16:45:37 INFO - -d DEST, --destination=DEST 16:45:37 INFO - Directory to install application into. [default: 16:45:37 INFO - "/builds/slave/test"] 16:45:37 INFO - --app=APP Application being installed. [default: firefox] 16:45:37 INFO - mkdir: /builds/slave/test/build/application 16:45:37 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'] 16:45:37 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 16:45:59 INFO - Reading from file tmpfile_stdout 16:45:59 INFO - Output received: 16:45:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:45:59 INFO - Running post-action listener: _resource_record_post_action 16:45:59 INFO - [mozharness: 2016-12-29 00:45:59.278338Z] Finished install step (success) 16:45:59 INFO - [mozharness: 2016-12-29 00:45:59.278467Z] Running run-tests step. 16:45:59 INFO - Running pre-action listener: _resource_record_pre_action 16:45:59 INFO - Running main action method: run_tests 16:45:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:59 INFO - 2016-12-28 16:45:59.293 screenresolution[1948:9660] starting screenresolution argv=screenresolution get 16:45:59 INFO - 2016-12-28 16:45:59.296 screenresolution[1948:9660] Display 0: 1600x1200x32@60 16:45:59 INFO - 2016-12-28 16:45:59.301 screenresolution[1949:9663] starting screenresolution argv=screenresolution list 16:45:59 INFO - Available Modes on Display 0 16:45:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:59 INFO - Intel Iris: 16:45:59 INFO - Chipset Model: Intel Iris 16:45:59 INFO - Type: GPU 16:45:59 INFO - Bus: Built-In 16:45:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:59 INFO - Vendor: Intel (0x8086) 16:45:59 INFO - Device ID: 0x0a2e 16:45:59 INFO - Revision ID: 0x0009 16:45:59 INFO - Displays: 16:45:59 INFO - Display: 16:45:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:59 INFO - Display Serial Number: 1600x1200 60 16:45:59 INFO - Main Display: Yes 16:45:59 INFO - Mirror: Off 16:45:59 INFO - Online: Yes 16:45:59 INFO - Rotation: Supported 16:45:59 INFO - Return code: 0 16:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:59 INFO - Python 2.7.3 16:45:59 INFO - Return code: 0 16:45:59 INFO - grabbing minidump binary from tooltool 16:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:59 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101199d30>, '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 0x1010c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10118ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:45:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:45:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:45:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:59 INFO - Return code: 0 16:45:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HxivbWiuSp/Render', 16:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:59 INFO - 'HOME': '/Users/cltbld', 16:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:59 INFO - 'LOGNAME': 'cltbld', 16:45:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:59 INFO - 'MOZ_NO_REMOTE': '1', 16:45:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:59 INFO - 'NO_EM_RESTART': '1', 16:45:59 INFO - 'PAGER': '/bin/cat', 16:45:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:59 INFO - 'PWD': '/builds/slave/test', 16:45:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:59 INFO - 'SHELL': '/bin/bash', 16:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e9C2JIkgdZ/Listeners', 16:45:59 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:59 INFO - 'USER': 'cltbld', 16:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:59 INFO - 'XPC_FLAGS': '0x0', 16:45:59 INFO - 'XPC_SERVICE_NAME': '0', 16:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:45:59 INFO - mozversion application_buildid: 20161228160136 16:45:59 INFO - mozversion application_changeset: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a 16:45:59 INFO - mozversion application_display_name: Nightly 16:45:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:59 INFO - mozversion application_name: Firefox 16:45:59 INFO - mozversion application_remotingname: firefox 16:45:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:45:59 INFO - mozversion application_vendor: Mozilla 16:45:59 INFO - mozversion application_version: 53.0a1 16:45:59 INFO - mozversion platform_buildid: 20161228160136 16:45:59 INFO - mozversion platform_changeset: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a 16:45:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:45:59 INFO - mozversion platform_version: 53.0a1 16:45:59 INFO - using testdate: 1482972359 16:45:59 INFO - actual date: 1482972359 16:45:59 INFO - starting webserver on 'localhost:49320' 16:45:59 INFO - SUITE-START | Running 2 tests 16:45:59 INFO - TEST-START | dromaeo_css 16:45:59 INFO - operating with platform_type : mac_ 16:45:59 INFO - Initialising browser for dromaeo_css test... 16:45:59 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpHUvVcz/profile http://localhost:49320/getInfo.html) 16:46:00 INFO - PROCESS | 1959 | 1482972360412 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 16:46:02 INFO - PROCESS | 1959 | 2016-12-28 16:46:02.652 plugin-container[1961:9816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 16:46:02 INFO - PROCESS | 1959 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:02 INFO - PROCESS | 1959 | 2016-12-28 16:46:02.656 plugin-container[1961:9816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 16:46:02 INFO - PROCESS | 1959 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:02 INFO - PROCESS | 1959 | 2016-12-28 16:46:02.656 plugin-container[1961:9816] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:46:02 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 16:46:02 INFO - PROCESS | 1959 | colorDepth:24 16:46:02 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 16:46:02 INFO - PROCESS | 1959 | __metrics 16:46:02 ERROR - PROCESS | 1959 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 16:46:03 INFO - TEST-INFO | 1959: exit 0 16:46:03 INFO - Browser initialized. 16:46:03 INFO - Running cycle 1/1 for dromaeo_css test... 16:46:03 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpHUvVcz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:46:05 INFO - PROCESS | 1962 | 2016-12-28 16:46:05.817 plugin-container[1963:9945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 16:46:05 INFO - PROCESS | 1962 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:05 INFO - PROCESS | 1962 | 2016-12-28 16:46:05.817 plugin-container[1963:9945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 16:46:05 INFO - PROCESS | 1962 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:46:05 INFO - PROCESS | 1962 | 2016-12-28 16:46:05.817 plugin-container[1963:9945] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:46:15 INFO - PROCESS | 1962 | RSS: Main: 232116224 16:46:15 INFO - PROCESS | 1962 | 16:49:17 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49320/tests/dromaeo/cssquery-ext.html) 16:49:20 INFO - PROCESS | 1962 | RSS: Main: 250122240 16:49:20 INFO - PROCESS | 1962 | 16:51:03 INFO - PROCESS | 1962 | ************************* 16:51:03 INFO - PROCESS | 1962 | A coding exception was thrown and uncaught in a Task. 16:51:03 INFO - PROCESS | 1962 | 16:51:03 INFO - PROCESS | 1962 | Full message: ReferenceError: fetch is not defined 16:51:03 INFO - PROCESS | 1962 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 16:51:03 INFO - PROCESS | 1962 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 16:51:03 INFO - PROCESS | 1962 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 16:51:03 INFO - PROCESS | 1962 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:51:03 INFO - PROCESS | 1962 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:51:03 INFO - PROCESS | 1962 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:51:03 INFO - PROCESS | 1962 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 16:51:03 INFO - PROCESS | 1962 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:51:03 INFO - PROCESS | 1962 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:51:03 INFO - PROCESS | 1962 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:51:03 INFO - PROCESS | 1962 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 16:51:03 INFO - PROCESS | 1962 | 16:51:03 INFO - PROCESS | 1962 | ************************* 16:52:22 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49320/tests/dromaeo/cssquery-ext.html (next: http://localhost:49320/tests/dromaeo/cssquery-jquery.html) 16:52:23 INFO - PROCESS | 1962 | RSS: Main: 260763648 16:52:23 INFO - PROCESS | 1962 | 16:55:25 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49320/tests/dromaeo/cssquery-mootools.html) 16:55:25 INFO - PROCESS | 1962 | RSS: Main: 269172736 16:55:25 INFO - PROCESS | 1962 | 16:58:27 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49320/tests/dromaeo/cssquery-prototype.html) 16:58:28 INFO - PROCESS | 1962 | RSS: Main: 265338880 16:58:28 INFO - PROCESS | 1962 | 17:01:31 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49320/tests/dromaeo/cssquery-yui.html) 17:01:32 INFO - PROCESS | 1962 | RSS: Main: 269938688 17:01:32 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49320/tests/dromaeo/cssquery-yui.html (next: http://localhost:49320/tests/dromaeo/cssquery-dojo.html) 17:04:35 INFO - PROCESS | 1962 | RSS: Main: 272105472 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | __start_tp_report 17:04:35 INFO - PROCESS | 1962 | _x_x_mozilla_page_load 17:04:35 INFO - PROCESS | 1962 | _x_x_mozilla_page_load_details 17:04:35 INFO - PROCESS | 1962 | |i|pagename|runs| 17:04:35 INFO - PROCESS | 1962 | |0;dojo.html;2652.761251487366;3118.703723146302;3148.858301376438;3318.518814771858;3391.3556424279386;5607.943920560793;5639.15412688097;5660.433956604337;5689.260396148495;5738.684372359529;5631.1271752878365;5676.176954341618;5698.230738850254;5726.885462290762;5749.453801888824;5126.436092029874;5146.382434107913;5195;5217;5294.364676238857;3832.2335532893394;4504.71615589557;5264.210368444726;5282.07563676357;5389.568834493231;5084.084864724348;5219.243209734567;5232.8691782705355;5257.605679574047;5311.442298558653;4247.787610619456;5119.436861945183;5125.564327032207;5152.819651312224;5164.9741751291;4624.976875115636;4734.715917044989;5121.769520371592;5151.922721159186;5212.478752124795;5491;5564.304461942257;5664.0937450007805;5698.8290351289525;5729.226554415142;6128.846778830521;6149.877002459971;6169.074638804171;6198.411150940653;6224.595401298901;6088.391160883921;6091.0863370494335;6126.571140020201;6137.346341658241;6149.385061493859;5750.2237197978575;5766.884662306772;5783.681897495594;5796.246487956539;5865.853353666193;6194.442500174916;6254.061890716338;6256.781012664626;6270.090836828635;6285.937140628626;4679.508651591534;5962.493188079062;6093.878122437526;6129.325774164839;6138.0179171332375;6104.481119104926;6150.415710507495;6155.292141403706;6172.69136543171;6176;6169.81490555284;6174.8147555573405;6220.626762394271;6224.533160013018;6225.875482490324;5699.081709425584;6089.421504957022;6180.381961803873;6215;6260.87304285233;4854.296127061556;4866.537678920497;4874.8537543873745;4951.306817045616;4967.528084831935;4361.454818147731;4409.470863496401;4440.378347031418;4440.822367105279;4472.776361181928;5798.246227990334;5815.360310365856;5822.796202132905;5874.618149820248;5895.322037965689;6083.087536869505;6133.365267548864;6253.187085678924;6274.717637706314;6289.245290565162;5766.942330576724;5768.278965129359;5777.133429985536;5780.826575202751;5808.535317174616;5311.123664595376;5386.353637563439;5428.67427954324;5443.455654434525;5462.754176062087;6292;6301.590396624297;6303.432691057827;6313.873722525524;6419.903701444482;6137.009281402767;6172.043333283348;6265.310815810349;6286.4342209201395;6295.213098362705;6187.876242475215;6194;6194.721237544231;6270.905936411049;6302.212223472066;5134;5153.665011774223;6059.727312271047;6139.969300153471;6201.0388389799655;5317.813876514303;6098.902197604474;6251.499880009679;6278.121063051268;6286.025666021774;6149.139120523219;6150.7847225346895;6169;6173.6913154343;6180.505559555315;5922.644641321534;5978.013627751372;5978.760849565969;6043.09353596964;6044.093385992136;5736.68448235351;5829.504492118217;5829.854253643693;5857.238558987305;5872.853178670567;6061.72722227492;6104.822969073451;6106.297775755845;6114.419130182626;6126.30393285589;5987.730552125252;5995.61028533144;6017.277926648655;6019.699015049143;6032.517398608013 17:04:35 INFO - PROCESS | 1962 | |1;ext.html;448.5290445032715;460.80185520226297;495.9801607935683;499.48157600893285;538.943670406004;37352.626473735254;39235;39373.01567460811;39411;39502.60497395025;39614;40212.19575608493;40222.19555608886;40268;40275.388984440586;8338.374621903351;9080.091990800933;9267.119623635745;9304.395214311047;9333.206677432427;8335.916640833604;11098.778024439507;11361.091112711005;11391.316521008725;11510.30938143714;11124.443777811077;11205;11211.775764484706;11216.327020378803;11236;11259.54961801527;11267.887321126807;11334.206605537616;11335.263207891501;11397.316161030365;11142.331460112338;11223.831642525369;11232.831507527311;11256.38089905055;11268.380239086848;39084.02289942774;39417.605823941965;39417.80291098555;39419;39612.20775584501;3944.23087497938;3975.940360894589;3991.102002049545;4024.678025757932;4119;29854.402911941856;30186.84906575453;30390;30436.54345184824;30473.39053218923;3163.6678148794504;3221.371832492665;3256.218507558193;3335.8999230023137;3343.8829640962695;449884;452098.9999999967;452658;453769;455489;54677;57752;57958;58121.41878581245;58193.12710309349;61852;62119.37880621226;62147;62161.689191553756;62216;15353.923230384;15543.533693989199;15580.922095389677;15584.92207538955;15585.688286234212;10815.351078935131;11091.5563377464;11995.64013079609;12077.27536347822;12083.972862306802;17147.63294578889;17394;20681.793182068286;21123.683144752842;21153.89423052906;8378.245957863699;8480.007198308393;8531.65873365071;8652.913470865211;8657.480551166951;778.766370088971;786.3237615650536;831.9084900660922;866.3632230310717;869.7521206456256;29616.297962754656;29803.394554775685;29951.550726739115;30081;30152.84923575368;877.3726785348506;963.6579014449909;974.1135566634329;1012.3824467075078;1031.3185717135389;12346.64186939436;12564.246145231315;13823.308834558231;13966.58100256994;14078;591803;669495;669613;669687;675167;48158.75920620444;48239.75880120577;48454.51545484571;48465.5153448461;48495.78760531015;1280.571008712092;1355.5729945067271;1360.6530334764573;1365.208179256029;1366.6515038665077;25359.11243106556;26473.60289595619;26623.60064598994;26710.86644566765;26756.59865102064;42936.57063429326;47020;48528.51471485381;48633.54099377024;50584.747076265856;573233.267667318;575712;577353;578525;579663;31300.686993129777;31932.840335798173;37202;37835.62164378321;37860;37257.813710931274;40952.18095638011;41048.589514104475;41619.37570936499;41649.1670166601;34707.305853883285;35133.297334052666;35327.82336088406;35478.46782298216;35815.283694325444;34451.4832277511;34807.82596087003;35291.470627940085;35498.64501354953;35819;33536.49695254622;33914.49128263029;33933.830330848185;40496.79751601223;41323;33810;38752.03119922025;39831.601683982786;40159.59840401559;41212.793936030124;28370.432591347646;30034.69965300319;31426.685733142374;31647.20881977969;31762.841185793925 17:04:35 INFO - PROCESS | 1962 | |2;jquery.html;367.40113614807865;370.4369358574966;374.597307894014;385.20070852980075;397.7454429165335;15692.843071569281;16079.83920160798;18340.81659183408;18968.430947071574;19835.900820495895;17363.73954390682;19159.712604310946;19674.213031478725;20034.29879954202;20064.398068057944;5578.581606379517;5586.636868603538;5822.767089316422;5833.912491312633;5835.416458354173;4639.953600463986;5573.637713548626;5737.856553586173;5749.798757043504;5757.510611598019;5462.945370546303;5687.971560142193;5692.089265717486;5693.430656934294;5714.914276285859;4709.552592503706;5536.474034966672;5625.887482250332;5643.463870932275;5658.34928983166;4112.321466957948;4897.240927656183;4898.975505122452;4925.187344088221;4960.53664169071;23782.76217237823;23969.801509924433;24125.758742412527;24360.90375933087;24388.146414875573;3030.3939212157657;3034.286942568495;3034.8937787177565;3035.9544606830914;3037.9544306835414;13926.234057126854;14616.853831461656;14861.925690371587;15235.695286094327;15258.847411525854;1544.3204989804563;1794.1657129434745;1801.864860135495;1805.1515787579817;1827.5979284557377;103535.96464035338;107323;107501.46249268782;108833;109237.90762092356;11200.719982000515;11208.66374008781;11275.097992160607;11362.772744545227;11552.59565915189;14037.5086871959;14049.297535123407;14078.225697586731;14088.647783805487;14112.788308175386;7900.565468899151;8005.479643823133;8122.756317310372;8182.4681395709085;8327.20891515305;7016.193137789118;7949.7217597383815;7962.840743185104;8114.431989760778;8115.594220288962;3781.3949768036982;4375.321825117112;4393.989382441992;4467.530909254547;4546.158960592301;1912.6652835753687;1953.658109830774;1959.8530110241504;1960.4218604023613;1963.646543622133;975.9902400976041;983.9163671088038;992.7170756334555;995.7659949911765;1026.9024442677933;15459.536213913601;16264;17969.3827555518;19336.70994935077;19627.509312267022;983.2674657380297;999.1307562420741;1001.6744558018673;1031.4327120083924;1034.7206254311293;8334.291585215324;9067.95466022679;10269.897301026895;10325.432101234353;10360.844587331045;530787;549813;551044.7343289853;551284;551957;97792;98102;100767;120218.79781202076;128553;852.3011130872625;1021.0708255487323;1044.1333693034762;1046.450613427941;1050.0392141190725;15429.922850385676;16694.998300102034;17364.82635173632;17944.820551794837;18117.728234076763;51733.96532069413;51823.963520730125;52217.95564088773;52332;53931;78086;78168;78553.60723196347;79360;80008;7772.572508512086;7856.332211762065;7884.526928384315;8028.397870159644;8125;9240.491772952284;10538.947305263424;11312.15158863072;12304.507819687113;12605.74788504243;10323.380597164194;10614.416207108683;10703.25150753534;10976.725581860517;11003.944980275048;10199.796004079728;10223.948880255552;10598.31110977784;10649.201309901633;10834.945825270823;10824.296420732628;10833.349999000086;10932.562697492329;11147.940945610155;11177.273477223955;7613.801880086153;7636.274553917369;7824.21757824213;7861.606919654108;7942.126366099609;7896;9034.774130646787;9313.860292095489;10594.417307048187;10781.460926953465 17:04:35 INFO - PROCESS | 1962 | |3;mootools.html;1229.7909355409586;1234.648125284294;1382.4193838587792;1384.7853582694684;1395.6022533577925;6094.939050609492;7735.651895664694;7929.444938854283;8320.667173313068;8359.289460395874;7848.921510784905;8180.181981801832;8232.588370581461;8288.803467081214;8308.875366869503;2257.841951063422;2307.8846057697133;2308.272894038381;2312.6877871487304;2336.6261398176293;2042.7548694156721;2115.6826476028564;2292.954140917189;2328.2549584133085;2350.764923507644;2200.8459407841456;2254.6505291679814;2273.9772602274015;2275.203678712454;2298.0118549023914;2076.1487790006177;2199.670049492573;2213.701150344699;2215.745189303235;2222.911083556664;1992.531755037565;2238.9029375606;2265.728112626479;2274.226762900622;2274.8976296066717;8164.6325915333955;8367.99584049918;8392;8400.83198336036;8401.36989725773;4979.676321039157;5136.743162841843;5146.047981123466;5148.742562871841;5155.304033955405;3338.749593780476;3420.0765793235937;3460;3476;3491.930161396783;1167.048855183023;1188.393919101256;1200.9819852702128;1224.2593231095148;1244.352936473038;10586.258961872672;10597.470126493721;10639.946800265949;10651.147908167328;10694.914491674126;4079.286124928126;4304.849330273425;4325.783710814447;4403.383526306319;4438.112377524508;7332.450066245053;8004.679812807539;8532.829343413097;8533.359998000175;8568.443051201652;3902.35611124167;4493.5281795411665;4939.506049395103;4952.876178095505;4964.975175124174;4081.795910204478;4212.178625168062;4604.976975115103;4615.492295847454;4697.976510117496;3505.4741788731385;3543.982280088583;3569.321828852562;3585.766925149857;3625.6011838697723;1064.9627263045757;1097.846301517788;1106.9557217711201;1115.5816568786704;1129.1362107987395;732.7765031665286;753.8756105242683;769.461377036077;789.0570767932265;811.6631597886928;5564.554835613141;5577.135543990688;5603.607747457639;5607.831765047055;5752.827415177551;882.7219425880827;928.3640706116332;938.4275591888942;967.3615413826841;989.8762654668167;3568.6431356864105;3602.657747513982;3696.5194524711615;3760.4735337052834;3836.808159592065;9306;9561.713148605553;9848.704538863845;9852.261080418997;9890.307678462583;9583.760405989906;9823.410595364157;9829.361091529028;9834.606615735434;9868.062534059252;7581.241875812485;7719.768406947801;7792.532448053135;7802.453828231969;7804.141544429995;4470.485894122087;4623.953760462487;5232.790688372423;5386.35363756336;5449.782008719607;9048.592813323285;9079.591418386057;9154.221891139328;9159.542022898962;9261.398009129385;9268.073192680678;9268.53657317145;9295.907040929775;9351.953240233755;9483;5378.515933565841;6608.942569189099;6647.302033286435;6701.564398314094;6763.5941843489545;7723;7783.182765809508;7793.844123117619;7797.220277972157;7820.530768153929;6980.476464265302;7082.716691332289;7092.964535177498;7107.538010029331;7203.963980180066;6861.279565645735;6900.654967251718;6912;6965.547239429421;6991.545549539263;6177.598456100519;6235.095911092775;6321.146645202839;6340.365963403623;6350.396712312323;4637.118947399983;4706;4737.763111844463;4745.83389581363;4851.029794041136;4694.976525117352;4776.904461910812;4831.903361932812;4834.371531700856;4839.128956787812 17:04:35 INFO - PROCESS | 1962 | |4;prototype.html;314.2552151401179;344.1074092491298;362.9129009037831;367.45799945081023;369.08835177112525;3940.625640564149;4090.8568200113;5235.397929238139;5341.839744807652;5492.203630473579;4406.449193850769;5013.874653133674;5032.220005899082;5233;5313.57491400688;3177;4160.895977600561;4161.897097269226;4202.663786897041;4220.240356735786;3271.7382609391193;3733.551973763152;3826.4069069294305;4037.1925614876995;4082.6937979651498;3357.8992630221132;3411.761176717631;4103.05629705168;4118.341065429532;4130.8554200602985;4100.528439229497;4120.475424093105;4126.319157339036;4126.8142933567915;4137.110521237948;2793.4971705093076;3196.488561830119;3637.363461394272;3705.7961812100325;3720.7395482316247;4230.773075808042;5054.9241761373605;5076.289319495274;5115.693058416507;5155.628410930976;4729.074867859668;4753.928691069636;4758.690685105457;4768.141734487791;4777.5700186982995;4343.413639158704;4865.9756701216265;5553.972230138864;5590.776368945238;5655;3542.557180352456;3881.9805900970596;4007.8196481158416;4031.23406552754;4040.979795101035;4625.866662667634;5597.992361374951;5825.88348233033;5840.853978650525;5851.795187168429;3285;3493.1441796759955;3948.624880636335;4125;4131.35963925592;3796.8101594920145;3825.062859599416;3988.940165897514;4132.772697501606;4233.661307095425;3854.69162467002;4451.421315229064;4664.603508701731;4739.786709598076;4800.543948324904;5646.068398714248;5661.405552417019;5697.23982171773;5716.514096301861;5741.66504289259;1100.4112799652148;1187.7149484123747;1233.9506419743288;1238.4674589926237;1273.3951373097705;888.9688860889967;904.8461761500429;916.9449833010041;964.3249725192253;964.9614015439304;4241.639460645818;4595.425571803525;4848.902677237728;5019.322391477104;5032.245163225472;5004.679055021862;5415.160650099241;5448.727563621803;5471.452854714496;5506.339239291311;4364.8472303469225;4717;4961.875953101129;5006.424261209934;5044.369453818273;2632.815702900817;2659.002873922279;2862.470442968058;2865.584490248902;2876.3528206153446;4728.858134255978;5198.766055527511;5239.266502689627;5560.248521715646;5604.674060264944;4635;4761.025098231108;5256.763445644955;5472.015037293326;5569.832905012856;4661.340847434747;5281.762320695655;5342.984832881818;5616.241807356118;5622.97188514063;4895.559399654048;5056.266841308064;5132.922086361906;5368.423156884795;5442.972785136208;5068.82259120929;5281.683099014072;5302.70835104073;5328.413874473727;5407.756650950638;5403.433369149622;5418.882158073744;5616.241807356118;5618.85952851182;5627.52166065889;3231.967680323167;3467.618561958233;3717.2343137009702;3874.612538746103;3900.8049597519444;4292.943168705543;5031.371078615173;5094.497123348547;5423.864403389947;5455.863603409946;4589.426321709786;4725.432948046119;5239.476052394883;5256.028989129077;5276.366835979707;4344.631441096045;4668.836590719308;4715.24556071027;5083.87290317745;5232.659877107976;5327.840164795062;5366.919496207638;5509.834704958857;5512.39363670004;5528.80649177293;4779.569838714532;4845.127876982177;4946.505349465025;5596.244506991504;5615.971920140537;5168.53483186515;5435.673859568438;5480.2875626169935;5483.2323474712775;5496.340439147168 17:04:35 INFO - PROCESS | 1962 | |5;yui.html;623.9688015599222;654.5810681164053;750.4559194583927;790.4308897593733;831.293400609482;14330.28348582572;15625.687486250268;15833.445829395945;15934.362625494996;15967.840321596766;13975.580732578013;14000;14370.784438233408;14465.927670361634;14530.273486325668;884.4472204871955;899.2491269789733;902.7336935604012;903.1239697493421;904.1275169461468;704.2218348724663;728.8505856299446;860.6815478273048;886.7827382291348;901.2744740483907;964.9517524123765;966.3284017607765;969.966985160805;974.4202199691173;977.7457860956167;948.1798244518511;951.5908159491416;963.1379914976058;963.1572374172599;964.2671569607114;669.5012215899137;715.1096884378982;719.8020544350293;723.5080145501057;724.6702750248625;13573.796393054112;13753.518626848112;14989.400423983137;14998.400063997427;15527.301271442811;12161.391930403444;14038.719225615532;14269.928650356682;14353.856461435356;14442.35009424568;10106.444145572064;11450.656480305604;11568.363739994296;11574.942125289319;12114.697132571668;3691.868674107863;3716.2381711749026;3727.310447567209;3789.4694742736037;3862.748921320105;79464.20535794625;96722;97399;98185;99012.99999999927;10864.456777161111;10958.726031849108;11005.944970275257;11151.107911367071;11281.435928203557;22387.440313991956;22555.661665075037;22566.87165641711;22640.981155848025;22667.319980400614;7679.462437629312;7738;7758.224177582197;7785.0657921048705;7934.246246606564;4207.726497777696;4241.1093670328955;4241.300185469363;4251.59609837065;4303.784810759449;3501.8249087546033;3694.261147770457;3881.437191607258;3885.514310711161;3961.4057891316534;679.5718697220719;682.2801943949181;687.1547995964991;694.8783962806489;701.7824474412948;611.2267981004034;628.1614045249552;630.7477009196358;632.2476253258608;653.9574927629784;8554;8674.56627168639;9176.128267814547;9351.672691455766;9472.857907131398;603.2640178981635;615.2985596419995;619.8822223777468;626.27352271365;627.5983370642792;4396.956030439718;4425.822967081345;4647.907041859144;4689.882752931204;4733.810647574059;89354;95885;106020;106147;107304;66715.3328466709;67067.99398009034;76153;91221.54389228011;93457.532712336;76279.61860190664;77430;78639;78994;79931.60034199792;9775.21798256152;9778.951105244429;9966.800663986534;9977.551010204414;9987.500624968869;32586;46424;49068.52794416173;49270;50849.49150508447;69651.60696786137;69703.65148174227;70051;70297.29702702907;70673.64663176652;12705.809412859;12991.545295914597;13005.869941300465;13266.86733132695;13363.198208107544;14520;14608;14690.779638305221;15000.399984000518;15387.846121539089;13317.533886313933;13683.657908552366;14011.929940350234;14127.64680882945;14216.933729970087;13086.345682716017;13402.396892139683;13580.524681636096;13742.175469471864;13948.02363834522;13418.865811342152;13441.975555499934;13510.729785404434;13571.59285221445;13948.442062317394;9868.851967220355;10288.742781430525;10564;11238.84162859747;11415.143864210051;9070.909290907006;9376.249900007846;10810.67567972962;10830.40432776205;10874.728131796768 17:04:35 INFO - PROCESS | 1962 | __end_tp_report 17:04:35 INFO - PROCESS | 1962 | __start_cc_report 17:04:35 INFO - PROCESS | 1962 | _x_x_mozilla_cycle_collect,374 17:04:35 INFO - PROCESS | 1962 | __end_cc_report 17:04:35 INFO - PROCESS | 1962 | __startTimestamp1482973475363__endTimestamp 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | ------- Summary: start ------- 17:04:35 INFO - PROCESS | 1962 | Number of tests: 6 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | [#0] dojo.html Cycles:165 Average:5670.74 Median:5872.85 stddev:696.19 (11.9%) stddev-sans-first:656.83 17:04:35 INFO - PROCESS | 1962 | Values: 2652.8 3118.7 3148.9 3318.5 3391.4 5607.9 5639.2 5660.4 5689.3 5738.7 5631.1 5676.2 5698.2 5726.9 5749.5 5126.4 5146.4 5195.0 5217.0 5294.4 3832.2 4504.7 5264.2 5282.1 5389.6 5084.1 5219.2 5232.9 5257.6 5311.4 4247.8 5119.4 5125.6 5152.8 5165.0 4625.0 4734.7 5121.8 5151.9 5212.5 5491.0 5564.3 5664.1 5698.8 5729.2 6128.8 6149.9 6169.1 6198.4 6224.6 6088.4 6091.1 6126.6 6137.3 6149.4 5750.2 5766.9 5783.7 5796.2 5865.9 6194.4 6254.1 6256.8 6270.1 6285.9 4679.5 5962.5 6093.9 6129.3 6138.0 6104.5 6150.4 6155.3 6172.7 6176.0 6169.8 6174.8 6220.6 6224.5 6225.9 5699.1 6089.4 6180.4 6215.0 6260.9 4854.3 4866.5 4874.9 4951.3 4967.5 4361.5 4409.5 4440.4 4440.8 4472.8 5798.2 5815.4 5822.8 5874.6 5895.3 6083.1 6133.4 6253.2 6274.7 6289.2 5766.9 5768.3 5777.1 5780.8 5808.5 5311.1 5386.4 5428.7 5443.5 5462.8 6292.0 6301.6 6303.4 6313.9 6419.9 6137.0 6172.0 6265.3 6286.4 6295.2 6187.9 6194.0 6194.7 6270.9 6302.2 5134.0 5153.7 6059.7 6140.0 6201.0 5317.8 6098.9 6251.5 6278.1 6286.0 6149.1 6150.8 6169.0 6173.7 6180.5 5922.6 5978.0 5978.8 6043.1 6044.1 5736.7 5829.5 5829.9 5857.2 5872.9 6061.7 6104.8 6106.3 6114.4 6126.3 5987.7 5995.6 6017.3 6019.7 6032.5 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | [#1] ext.html Cycles:180 Average:69179.55 Median:37847.81 stddev:151852.13 (401.2%) stddev-sans-first:152190.43 17:04:35 INFO - PROCESS | 1962 | Values: 448.5 460.8 496.0 499.5 538.9 37352.6 39235.0 39373.0 39411.0 39502.6 39614.0 40212.2 40222.2 40268.0 40275.4 8338.4 9080.1 9267.1 9304.4 9333.2 8335.9 11098.8 11361.1 11391.3 11510.3 11124.4 11205.0 11211.8 11216.3 11236.0 11259.5 11267.9 11334.2 11335.3 11397.3 11142.3 11223.8 11232.8 11256.4 11268.4 39084.0 39417.6 39417.8 39419.0 39612.2 3944.2 3975.9 3991.1 4024.7 4119.0 29854.4 30186.8 30390.0 30436.5 30473.4 3163.7 3221.4 3256.2 3335.9 3343.9 449884.0 452099.0 452658.0 453769.0 455489.0 54677.0 57752.0 57958.0 58121.4 58193.1 61852.0 62119.4 62147.0 62161.7 62216.0 15353.9 15543.5 15580.9 15584.9 15585.7 10815.4 11091.6 11995.6 12077.3 12084.0 17147.6 17394.0 20681.8 21123.7 21153.9 8378.2 8480.0 8531.7 8652.9 8657.5 778.8 786.3 831.9 866.4 869.8 29616.3 29803.4 29951.6 30081.0 30152.8 877.4 963.7 974.1 1012.4 1031.3 12346.6 12564.2 13823.3 13966.6 14078.0 591803.0 669495.0 669613.0 669687.0 675167.0 48158.8 48239.8 48454.5 48465.5 48495.8 1280.6 1355.6 1360.7 1365.2 1366.7 25359.1 26473.6 26623.6 26710.9 26756.6 42936.6 47020.0 48528.5 48633.5 50584.7 573233.3 575712.0 577353.0 578525.0 579663.0 31300.7 31932.8 37202.0 37835.6 37860.0 37257.8 40952.2 41048.6 41619.4 41649.2 34707.3 35133.3 35327.8 35478.5 35815.3 34451.5 34807.8 35291.5 35498.6 35819.0 33536.5 33914.5 33933.8 40496.8 41323.0 33810.0 38752.0 39831.6 40159.6 41212.8 28370.4 30034.7 31426.7 31647.2 31762.8 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | [#2] jquery.html Cycles:180 Average:32403.77 Median:3032.34 stddev:91161.78 (3006.3%) stddev-sans-first:91385.78 17:04:35 INFO - PROCESS | 1962 | Values: 367.4 370.4 374.6 385.2 397.7 15692.8 16079.8 18340.8 18968.4 19835.9 17363.7 19159.7 19674.2 20034.3 20064.4 5578.6 5586.6 5822.8 5833.9 5835.4 4640.0 5573.6 5737.9 5749.8 5757.5 5462.9 5688.0 5692.1 5693.4 5714.9 4709.6 5536.5 5625.9 5643.5 5658.3 4112.3 4897.2 4899.0 4925.2 4960.5 23782.8 23969.8 24125.8 24360.9 24388.1 3030.4 3034.3 3034.9 3036.0 3038.0 13926.2 14616.9 14861.9 15235.7 15258.8 1544.3 1794.2 1801.9 1805.2 1827.6 103536.0 107323.0 107501.5 108833.0 109237.9 11200.7 11208.7 11275.1 11362.8 11552.6 14037.5 14049.3 14078.2 14088.6 14112.8 7900.6 8005.5 8122.8 8182.5 8327.2 7016.2 7949.7 7962.8 8114.4 8115.6 3781.4 4375.3 4394.0 4467.5 4546.2 1912.7 1953.7 1959.9 1960.4 1963.6 976.0 983.9 992.7 995.8 1026.9 15459.5 16264.0 17969.4 19336.7 19627.5 983.3 999.1 1001.7 1031.4 1034.7 8334.3 9068.0 10269.9 10325.4 10360.8 530787.0 549813.0 551044.7 551284.0 551957.0 97792.0 98102.0 100767.0 120218.8 128553.0 852.3 1021.1 1044.1 1046.5 1050.0 15429.9 16695.0 17364.8 17944.8 18117.7 51734.0 51824.0 52218.0 52332.0 53931.0 78086.0 78168.0 78553.6 79360.0 80008.0 7772.6 7856.3 7884.5 8028.4 8125.0 9240.5 10538.9 11312.2 12304.5 12605.7 10323.4 10614.4 10703.3 10976.7 11003.9 10199.8 10223.9 10598.3 10649.2 10834.9 10824.3 10833.3 10932.6 11147.9 11177.3 7613.8 7636.3 7824.2 7861.6 7942.1 7896.0 9034.8 9313.9 10594.4 10781.5 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | [#3] mootools.html Cycles:180 Average:5254.61 Median:5152.02 stddev:2899.73 (56.3%) stddev-sans-first:2892.08 17:04:35 INFO - PROCESS | 1962 | Values: 1229.8 1234.6 1382.4 1384.8 1395.6 6094.9 7735.7 7929.4 8320.7 8359.3 7848.9 8180.2 8232.6 8288.8 8308.9 2257.8 2307.9 2308.3 2312.7 2336.6 2042.8 2115.7 2293.0 2328.3 2350.8 2200.8 2254.7 2274.0 2275.2 2298.0 2076.1 2199.7 2213.7 2215.7 2222.9 1992.5 2238.9 2265.7 2274.2 2274.9 8164.6 8368.0 8392.0 8400.8 8401.4 4979.7 5136.7 5146.0 5148.7 5155.3 3338.7 3420.1 3460.0 3476.0 3491.9 1167.0 1188.4 1201.0 1224.3 1244.4 10586.3 10597.5 10639.9 10651.1 10694.9 4079.3 4304.8 4325.8 4403.4 4438.1 7332.5 8004.7 8532.8 8533.4 8568.4 3902.4 4493.5 4939.5 4952.9 4965.0 4081.8 4212.2 4605.0 4615.5 4698.0 3505.5 3544.0 3569.3 3585.8 3625.6 1065.0 1097.8 1107.0 1115.6 1129.1 732.8 753.9 769.5 789.1 811.7 5564.6 5577.1 5603.6 5607.8 5752.8 882.7 928.4 938.4 967.4 989.9 3568.6 3602.7 3696.5 3760.5 3836.8 9306.0 9561.7 9848.7 9852.3 9890.3 9583.8 9823.4 9829.4 9834.6 9868.1 7581.2 7719.8 7792.5 7802.5 7804.1 4470.5 4624.0 5232.8 5386.4 5449.8 9048.6 9079.6 9154.2 9159.5 9261.4 9268.1 9268.5 9295.9 9352.0 9483.0 5378.5 6608.9 6647.3 6701.6 6763.6 7723.0 7783.2 7793.8 7797.2 7820.5 6980.5 7082.7 7093.0 7107.5 7204.0 6861.3 6900.7 6912.0 6965.5 6991.5 6177.6 6235.1 6321.1 6340.4 6350.4 4637.1 4706.0 4737.8 4745.8 4851.0 4695.0 4776.9 4831.9 4834.4 4839.1 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | [#4] prototype.html Cycles:180 Average:4381.21 Median:4822.84 stddev:1316.19 (27.3%) stddev-sans-first:1283.99 17:04:35 INFO - PROCESS | 1962 | Values: 314.3 344.1 362.9 367.5 369.1 3940.6 4090.9 5235.4 5341.8 5492.2 4406.4 5013.9 5032.2 5233.0 5313.6 3177.0 4160.9 4161.9 4202.7 4220.2 3271.7 3733.6 3826.4 4037.2 4082.7 3357.9 3411.8 4103.1 4118.3 4130.9 4100.5 4120.5 4126.3 4126.8 4137.1 2793.5 3196.5 3637.4 3705.8 3720.7 4230.8 5054.9 5076.3 5115.7 5155.6 4729.1 4753.9 4758.7 4768.1 4777.6 4343.4 4866.0 5554.0 5590.8 5655.0 3542.6 3882.0 4007.8 4031.2 4041.0 4625.9 5598.0 5825.9 5840.9 5851.8 3285.0 3493.1 3948.6 4125.0 4131.4 3796.8 3825.1 3988.9 4132.8 4233.7 3854.7 4451.4 4664.6 4739.8 4800.5 5646.1 5661.4 5697.2 5716.5 5741.7 1100.4 1187.7 1234.0 1238.5 1273.4 889.0 904.8 916.9 964.3 965.0 4241.6 4595.4 4848.9 5019.3 5032.2 5004.7 5415.2 5448.7 5471.5 5506.3 4364.8 4717.0 4961.9 5006.4 5044.4 2632.8 2659.0 2862.5 2865.6 2876.4 4728.9 5198.8 5239.3 5560.2 5604.7 4635.0 4761.0 5256.8 5472.0 5569.8 4661.3 5281.8 5343.0 5616.2 5623.0 4895.6 5056.3 5132.9 5368.4 5443.0 5068.8 5281.7 5302.7 5328.4 5407.8 5403.4 5418.9 5616.2 5618.9 5627.5 3232.0 3467.6 3717.2 3874.6 3900.8 4292.9 5031.4 5094.5 5423.9 5455.9 4589.4 4725.4 5239.5 5256.0 5276.4 4344.6 4668.8 4715.2 5083.9 5232.7 5327.8 5366.9 5509.8 5512.4 5528.8 4779.6 4845.1 4946.5 5596.2 5616.0 5168.5 5435.7 5480.3 5483.2 5496.3 17:04:35 INFO - PROCESS | 1962 | 17:04:35 INFO - PROCESS | 1962 | [#5] yui.html Cycles:180 Average:19752.57 Median:4711.85 stddev:27833.64 (590.7%) stddev-sans-first:27874.66 17:04:35 INFO - PROCESS | 1962 | Values: 624.0 654.6 750.5 790.4 831.3 14330.3 15625.7 15833.4 15934.4 15967.8 13975.6 14000.0 14370.8 14465.9 14530.3 884.4 899.2 902.7 903.1 904.1 704.2 728.9 860.7 886.8 901.3 965.0 966.3 970.0 974.4 977.7 948.2 951.6 963.1 963.2 964.3 669.5 715.1 719.8 723.5 724.7 13573.8 13753.5 14989.4 14998.4 15527.3 12161.4 14038.7 14269.9 14353.9 14442.4 10106.4 11450.7 11568.4 11574.9 12114.7 3691.9 3716.2 3727.3 3789.5 3862.7 79464.2 96722.0 97399.0 98185.0 99013.0 10864.5 10958.7 11005.9 11151.1 11281.4 22387.4 22555.7 22566.9 22641.0 22667.3 7679.5 7738.0 7758.2 7785.1 7934.2 4207.7 4241.1 4241.3 4251.6 4303.8 3501.8 3694.3 3881.4 3885.5 3961.4 679.6 682.3 687.2 694.9 701.8 611.2 628.2 630.7 632.2 654.0 8554.0 8674.6 9176.1 9351.7 9472.9 603.3 615.3 619.9 626.3 627.6 4397.0 4425.8 4647.9 4689.9 4733.8 89354.0 95885.0 106020.0 106147.0 107304.0 66715.3 67068.0 76153.0 91221.5 93457.5 76279.6 77430.0 78639.0 78994.0 79931.6 9775.2 9779.0 9966.8 9977.6 9987.5 32586.0 46424.0 49068.5 49270.0 50849.5 69651.6 69703.7 70051.0 70297.3 70673.6 12705.8 12991.5 13005.9 13266.9 13363.2 14520.0 14608.0 14690.8 15000.4 15387.8 13317.5 13683.7 14011.9 14127.6 14216.9 13086.3 13402.4 13580.5 13742.2 13948.0 13418.9 13442.0 13510.7 13571.6 13948.4 9868.9 10288.7 10564.0 11238.8 11415.1 9070.9 9376.2 10810.7 10830.4 10874.7 17:04:35 INFO - PROCESS | 1962 | -------- Summary: end -------- 17:04:35 INFO - PROCESS | 1962 | 17:04:36 INFO - TEST-INFO | 1962: exit 0 17:04:36 INFO - TEST-OK | dromaeo_css | took 1116177ms 17:04:36 INFO - TEST-START | kraken 17:04:36 INFO - operating with platform_type : mac_ 17:04:36 INFO - Initialising browser for kraken test... 17:04:36 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpc7KqLN/profile http://localhost:49320/getInfo.html) 17:04:36 INFO - PROCESS | 1970 | 1482973476563 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:04:38 INFO - PROCESS | 1970 | 2016-12-28 17:04:38.991 plugin-container[1971:12113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 17:04:38 INFO - PROCESS | 1970 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:38 INFO - PROCESS | 1970 | 2016-12-28 17:04:38.994 plugin-container[1971:12113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 17:04:38 INFO - PROCESS | 1970 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:38 INFO - PROCESS | 1970 | 2016-12-28 17:04:38.994 plugin-container[1971:12113] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:39 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 17:04:39 INFO - PROCESS | 1970 | colorDepth:24 17:04:39 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 17:04:39 INFO - PROCESS | 1970 | __metrics 17:04:39 ERROR - PROCESS | 1970 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 17:04:39 INFO - TEST-INFO | 1970: exit 0 17:04:39 INFO - Browser initialized. 17:04:39 INFO - Running cycle 1/1 for kraken test... 17:04:39 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpc7KqLN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:42 INFO - PROCESS | 1972 | 2016-12-28 17:04:42.321 plugin-container[1973:12231] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 17:04:42 INFO - PROCESS | 1972 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:42 INFO - PROCESS | 1972 | 2016-12-28 17:04:42.322 plugin-container[1973:12231] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.CFPasteboardClient' 17:04:42 INFO - PROCESS | 1972 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:42 INFO - PROCESS | 1972 | 2016-12-28 17:04:42.322 plugin-container[1973:12231] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:51 INFO - PROCESS | 1972 | RSS: Main: 231600128 17:04:51 INFO - PROCESS | 1972 | 17:04:55 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49320/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:56 INFO - PROCESS | 1972 | RSS: Main: 244572160 17:04:56 INFO - PROCESS | 1972 | 17:04:58 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49320/tests/kraken/driver.html?testName=audio-dft) 17:04:59 INFO - PROCESS | 1972 | RSS: Main: 252809216 17:04:59 INFO - PROCESS | 1972 | 17:05:02 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49320/tests/kraken/driver.html?testName=audio-fft) 17:05:03 INFO - PROCESS | 1972 | RSS: Main: 255475712 17:05:03 INFO - PROCESS | 1972 | 17:05:05 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49320/tests/kraken/driver.html?testName=audio-oscillator) 17:05:05 INFO - PROCESS | 1972 | RSS: Main: 256634880 17:05:05 INFO - PROCESS | 1972 | 17:05:07 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:05:08 INFO - PROCESS | 1972 | RSS: Main: 256929792 17:05:08 INFO - PROCESS | 1972 | 17:05:11 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49320/tests/kraken/driver.html?testName=imaging-darkroom) 17:05:12 INFO - PROCESS | 1972 | RSS: Main: 263798784 17:05:12 INFO - PROCESS | 1972 | 17:05:15 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49320/tests/kraken/driver.html?testName=imaging-desaturate) 17:05:15 INFO - PROCESS | 1972 | RSS: Main: 263090176 17:05:15 INFO - PROCESS | 1972 | 17:05:18 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49320/tests/kraken/driver.html?testName=json-parse-financial) 17:05:19 INFO - PROCESS | 1972 | RSS: Main: 262975488 17:05:19 INFO - PROCESS | 1972 | 17:05:21 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:05:21 INFO - PROCESS | 1972 | RSS: Main: 262041600 17:05:21 INFO - PROCESS | 1972 | 17:05:23 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:05:24 INFO - PROCESS | 1972 | RSS: Main: 262078464 17:05:24 INFO - PROCESS | 1972 | 17:05:26 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:05:27 INFO - PROCESS | 1972 | RSS: Main: 262152192 17:05:27 INFO - PROCESS | 1972 | 17:05:30 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:05:30 INFO - PROCESS | 1972 | RSS: Main: 262381568 17:05:30 INFO - PROCESS | 1972 | 17:05:33 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:34 INFO - PROCESS | 1972 | RSS: Main: 262270976 17:05:34 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49320/tests/kraken/driver.html?testName=ai-astar) 17:05:36 INFO - PROCESS | 1972 | RSS: Main: 264011776 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | __start_tp_report 17:05:36 INFO - PROCESS | 1972 | _x_x_mozilla_page_load 17:05:36 INFO - PROCESS | 1972 | _x_x_mozilla_page_load_details 17:05:36 INFO - PROCESS | 1972 | |i|pagename|runs| 17:05:36 INFO - PROCESS | 1972 | |0;ai-astar;104;117;119;118;118;116;117;140;116;117 17:05:36 INFO - PROCESS | 1972 | |1;audio-beat-detection;160;114;112;145;137;142;146;118;137;115 17:05:36 INFO - PROCESS | 1972 | |2;audio-dft;209;216;209;205;195;201;198;198;199;278 17:05:36 INFO - PROCESS | 1972 | |3;audio-fft;90;60;59;89;62;58;59;60;74;59 17:05:36 INFO - PROCESS | 1972 | |4;audio-oscillator;87;71;85;75;100;93;85;72;73;87 17:05:36 INFO - PROCESS | 1972 | |5;imaging-gaussian-blur;127;122;88;87;112;79;135;130;108;86 17:05:36 INFO - PROCESS | 1972 | |6;imaging-darkroom;96;97;153;95;94;96;96;119;96;96 17:05:36 INFO - PROCESS | 1972 | |7;imaging-desaturate;91;93;83;147;144;111;96;91;92;95 17:05:36 INFO - PROCESS | 1972 | |8;json-parse-financial;63;61;62;61;63;63;62;63;64;61 17:05:36 INFO - PROCESS | 1972 | |9;json-stringify-tinderbox;69;76;83;77;75;72;72;73;73;74 17:05:36 INFO - PROCESS | 1972 | |10;stanford-crypto-aes;90;105;112;113;88;89;91;86;88;88 17:05:36 INFO - PROCESS | 1972 | |11;stanford-crypto-ccm;146;163;140;143;140;143;142;149;215;172 17:05:36 INFO - PROCESS | 1972 | |12;stanford-crypto-pbkdf2;174;175;169;174;176;174;175;177;171;171 17:05:36 INFO - PROCESS | 1972 | |13;stanford-crypto-sha256-iterative;70;86;68;69;70;70;68;69;67;68 17:05:36 INFO - PROCESS | 1972 | __end_tp_report 17:05:36 INFO - PROCESS | 1972 | __start_cc_report 17:05:36 INFO - PROCESS | 1972 | _x_x_mozilla_cycle_collect,942 17:05:36 INFO - PROCESS | 1972 | __end_cc_report 17:05:36 INFO - PROCESS | 1972 | __startTimestamp1482973536430__endTimestamp 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | ------- Summary: start ------- 17:05:36 INFO - PROCESS | 1972 | Number of tests: 14 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#0] ai-astar Cycles:10 Average:118.20 Median:117.50 stddev:8.77 (7.5%) stddev-sans-first:7.64 17:05:36 INFO - PROCESS | 1972 | Values: 104.0 117.0 119.0 118.0 118.0 116.0 117.0 140.0 116.0 117.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:139.50 stddev:16.68 (12.0%) stddev-sans-first:14.45 17:05:36 INFO - PROCESS | 1972 | Values: 160.0 114.0 112.0 145.0 137.0 142.0 146.0 118.0 137.0 115.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#2] audio-dft Cycles:10 Average:210.80 Median:207.00 stddev:24.48 (11.8%) stddev-sans-first:25.96 17:05:36 INFO - PROCESS | 1972 | Values: 209.0 216.0 209.0 205.0 195.0 201.0 198.0 198.0 199.0 278.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#3] audio-fft Cycles:10 Average:67.00 Median:61.00 stddev:12.73 (20.9%) stddev-sans-first:10.43 17:05:36 INFO - PROCESS | 1972 | Values: 90.0 60.0 59.0 89.0 62.0 58.0 59.0 60.0 74.0 59.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:85.00 stddev:9.76 (11.5%) stddev-sans-first:10.23 17:05:36 INFO - PROCESS | 1972 | Values: 87.0 71.0 85.0 75.0 100.0 93.0 85.0 72.0 73.0 87.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#5] imaging-gaussian-blur Cycles:10 Average:107.40 Median:107.00 stddev:20.95 (19.6%) stddev-sans-first:20.98 17:05:36 INFO - PROCESS | 1972 | Values: 127.0 122.0 88.0 87.0 112.0 79.0 135.0 130.0 108.0 86.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#6] imaging-darkroom Cycles:10 Average:103.80 Median:96.00 stddev:18.78 (19.6%) stddev-sans-first:19.71 17:05:36 INFO - PROCESS | 1972 | Values: 96.0 97.0 153.0 95.0 94.0 96.0 96.0 119.0 96.0 96.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#7] imaging-desaturate Cycles:10 Average:104.30 Median:91.50 stddev:22.82 (24.9%) stddev-sans-first:23.69 17:05:36 INFO - PROCESS | 1972 | Values: 91.0 93.0 83.0 147.0 144.0 111.0 96.0 91.0 92.0 95.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:63.00 stddev:1.06 (1.7%) stddev-sans-first:1.09 17:05:36 INFO - PROCESS | 1972 | Values: 63.0 61.0 62.0 61.0 63.0 63.0 62.0 63.0 64.0 61.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:74.50 stddev:3.78 (5.1%) stddev-sans-first:3.46 17:05:36 INFO - PROCESS | 1972 | Values: 69.0 76.0 83.0 77.0 75.0 72.0 72.0 73.0 73.0 74.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:88.00 stddev:10.64 (12.1%) stddev-sans-first:11.13 17:05:36 INFO - PROCESS | 1972 | Values: 90.0 105.0 112.0 113.0 88.0 89.0 91.0 86.0 88.0 88.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:147.50 stddev:23.51 (15.9%) stddev-sans-first:24.70 17:05:36 INFO - PROCESS | 1972 | Values: 146.0 163.0 140.0 143.0 140.0 143.0 142.0 149.0 215.0 172.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.50 stddev:2.50 (1.4%) stddev-sans-first:2.65 17:05:36 INFO - PROCESS | 1972 | Values: 174.0 175.0 169.0 174.0 176.0 174.0 175.0 177.0 171.0 171.0 17:05:36 INFO - PROCESS | 1972 | 17:05:36 INFO - PROCESS | 1972 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:69.50 stddev:5.54 (8.0%) stddev-sans-first:5.88 17:05:36 INFO - PROCESS | 1972 | Values: 70.0 86.0 68.0 69.0 70.0 70.0 68.0 69.0 67.0 68.0 17:05:36 INFO - PROCESS | 1972 | -------- Summary: end -------- 17:05:36 INFO - PROCESS | 1972 | 17:05:37 INFO - TEST-INFO | 1972: exit 0 17:05:37 INFO - TEST-OK | kraken | took 61249ms 17:05:37 INFO - SUITE-END | took 1177s 17:05:37 INFO - Completed test suite (00:19:37) 17:05:37 INFO - javascript benchmark 17:05:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7123.303696292401, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2652.761251487366, 3118.703723146302, 3148.858301376438, 3318.518814771858, 3391.3556424279386, 5607.943920560793, 5639.15412688097, 5660.433956604337, 5689.260396148495, 5738.684372359529, 5631.1271752878365, 5676.176954341618, 5698.230738850254, 5726.885462290762, 5749.453801888824, 5126.436092029874, 5146.382434107913, 5195.0, 5217.0, 5294.364676238857, 3832.2335532893394, 4504.71615589557, 5264.210368444726, 5282.07563676357, 5389.568834493231, 5084.084864724348, 5219.243209734567, 5232.8691782705355, 5257.605679574047, 5311.442298558653, 4247.787610619456, 5119.436861945183, 5125.564327032207, 5152.819651312224, 5164.9741751291, 4624.976875115636, 4734.715917044989, 5121.769520371592, 5151.922721159186, 5212.478752124795, 5491.0, 5564.304461942257, 5664.0937450007805, 5698.8290351289525, 5729.226554415142, 6128.846778830521, 6149.877002459971, 6169.074638804171, 6198.411150940653, 6224.595401298901, 6088.391160883921, 6091.0863370494335, 6126.571140020201, 6137.346341658241, 6149.385061493859, 5750.2237197978575, 5766.884662306772, 5783.681897495594, 5796.246487956539, 5865.853353666193, 6194.442500174916, 6254.061890716338, 6256.781012664626, 6270.090836828635, 6285.937140628626, 4679.508651591534, 5962.493188079062, 6093.878122437526, 6129.325774164839, 6138.0179171332375, 6104.481119104926, 6150.415710507495, 6155.292141403706, 6172.69136543171, 6176.0, 6169.81490555284, 6174.8147555573405, 6220.626762394271, 6224.533160013018, 6225.875482490324, 5699.081709425584, 6089.421504957022, 6180.381961803873, 6215.0, 6260.87304285233, 4854.296127061556, 4866.537678920497, 4874.8537543873745, 4951.306817045616, 4967.528084831935, 4361.454818147731, 4409.470863496401, 4440.378347031418, 4440.822367105279, 4472.776361181928, 5798.246227990334, 5815.360310365856, 5822.796202132905, 5874.618149820248, 5895.322037965689, 6083.087536869505, 6133.365267548864, 6253.187085678924, 6274.717637706314, 6289.245290565162, 5766.942330576724, 5768.278965129359, 5777.133429985536, 5780.826575202751, 5808.535317174616, 5311.123664595376, 5386.353637563439, 5428.67427954324, 5443.455654434525, 5462.754176062087, 6292.0, 6301.590396624297, 6303.432691057827, 6313.873722525524, 6419.903701444482, 6137.009281402767, 6172.043333283348, 6265.310815810349, 6286.4342209201395, 6295.213098362705, 6187.876242475215, 6194.0, 6194.721237544231, 6270.905936411049, 6302.212223472066, 5134.0, 5153.665011774223, 6059.727312271047, 6139.969300153471, 6201.0388389799655, 5317.813876514303, 6098.902197604474, 6251.499880009679, 6278.121063051268, 6286.025666021774, 6149.139120523219, 6150.7847225346895, 6169.0, 6173.6913154343, 6180.505559555315, 5922.644641321534, 5978.013627751372, 5978.760849565969, 6043.09353596964, 6044.093385992136, 5736.68448235351, 5829.504492118217, 5829.854253643693, 5857.238558987305, 5872.853178670567, 6061.72722227492, 6104.822969073451, 6106.297775755845, 6114.419130182626, 6126.30393285589, 5987.730552125252, 5995.61028533144, 6017.277926648655, 6019.699015049143, 6032.517398608013], "value": 5623.449416727737, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.5290445032715, 460.80185520226297, 495.9801607935683, 499.48157600893285, 538.943670406004, 37352.626473735254, 39235.0, 39373.01567460811, 39411.0, 39502.60497395025, 39614.0, 40212.19575608493, 40222.19555608886, 40268.0, 40275.388984440586, 8338.374621903351, 9080.091990800933, 9267.119623635745, 9304.395214311047, 9333.206677432427, 8335.916640833604, 11098.778024439507, 11361.091112711005, 11391.316521008725, 11510.30938143714, 11124.443777811077, 11205.0, 11211.775764484706, 11216.327020378803, 11236.0, 11259.54961801527, 11267.887321126807, 11334.206605537616, 11335.263207891501, 11397.316161030365, 11142.331460112338, 11223.831642525369, 11232.831507527311, 11256.38089905055, 11268.380239086848, 39084.02289942774, 39417.605823941965, 39417.80291098555, 39419.0, 39612.20775584501, 3944.23087497938, 3975.940360894589, 3991.102002049545, 4024.678025757932, 4119.0, 29854.402911941856, 30186.84906575453, 30390.0, 30436.54345184824, 30473.39053218923, 3163.6678148794504, 3221.371832492665, 3256.218507558193, 3335.8999230023137, 3343.8829640962695, 449884.0, 452098.9999999967, 452658.0, 453769.0, 455489.0, 54677.0, 57752.0, 57958.0, 58121.41878581245, 58193.12710309349, 61852.0, 62119.37880621226, 62147.0, 62161.689191553756, 62216.0, 15353.923230384, 15543.533693989199, 15580.922095389677, 15584.92207538955, 15585.688286234212, 10815.351078935131, 11091.5563377464, 11995.64013079609, 12077.27536347822, 12083.972862306802, 17147.63294578889, 17394.0, 20681.793182068286, 21123.683144752842, 21153.89423052906, 8378.245957863699, 8480.007198308393, 8531.65873365071, 8652.913470865211, 8657.480551166951, 778.766370088971, 786.3237615650536, 831.9084900660922, 866.3632230310717, 869.7521206456256, 29616.297962754656, 29803.394554775685, 29951.550726739115, 30081.0, 30152.84923575368, 877.3726785348506, 963.6579014449909, 974.1135566634329, 1012.3824467075078, 1031.3185717135389, 12346.64186939436, 12564.246145231315, 13823.308834558231, 13966.58100256994, 14078.0, 591803.0, 669495.0, 669613.0, 669687.0, 675167.0, 48158.75920620444, 48239.75880120577, 48454.51545484571, 48465.5153448461, 48495.78760531015, 1280.571008712092, 1355.5729945067271, 1360.6530334764573, 1365.208179256029, 1366.6515038665077, 25359.11243106556, 26473.60289595619, 26623.60064598994, 26710.86644566765, 26756.59865102064, 42936.57063429326, 47020.0, 48528.51471485381, 48633.54099377024, 50584.747076265856, 573233.267667318, 575712.0, 577353.0, 578525.0, 579663.0, 31300.686993129777, 31932.840335798173, 37202.0, 37835.62164378321, 37860.0, 37257.813710931274, 40952.18095638011, 41048.589514104475, 41619.37570936499, 41649.1670166601, 34707.305853883285, 35133.297334052666, 35327.82336088406, 35478.46782298216, 35815.283694325444, 34451.4832277511, 34807.82596087003, 35291.470627940085, 35498.64501354953, 35819.0, 33536.49695254622, 33914.49128263029, 33933.830330848185, 40496.79751601223, 41323.0, 33810.0, 38752.03119922025, 39831.601683982786, 40159.59840401559, 41212.793936030124, 28370.432591347646, 30034.69965300319, 31426.685733142374, 31647.20881977969, 31762.841185793925], "value": 19937.505095455752, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.40113614807865, 370.4369358574966, 374.597307894014, 385.20070852980075, 397.7454429165335, 15692.843071569281, 16079.83920160798, 18340.81659183408, 18968.430947071574, 19835.900820495895, 17363.73954390682, 19159.712604310946, 19674.213031478725, 20034.29879954202, 20064.398068057944, 5578.581606379517, 5586.636868603538, 5822.767089316422, 5833.912491312633, 5835.416458354173, 4639.953600463986, 5573.637713548626, 5737.856553586173, 5749.798757043504, 5757.510611598019, 5462.945370546303, 5687.971560142193, 5692.089265717486, 5693.430656934294, 5714.914276285859, 4709.552592503706, 5536.474034966672, 5625.887482250332, 5643.463870932275, 5658.34928983166, 4112.321466957948, 4897.240927656183, 4898.975505122452, 4925.187344088221, 4960.53664169071, 23782.76217237823, 23969.801509924433, 24125.758742412527, 24360.90375933087, 24388.146414875573, 3030.3939212157657, 3034.286942568495, 3034.8937787177565, 3035.9544606830914, 3037.9544306835414, 13926.234057126854, 14616.853831461656, 14861.925690371587, 15235.695286094327, 15258.847411525854, 1544.3204989804563, 1794.1657129434745, 1801.864860135495, 1805.1515787579817, 1827.5979284557377, 103535.96464035338, 107323.0, 107501.46249268782, 108833.0, 109237.90762092356, 11200.719982000515, 11208.66374008781, 11275.097992160607, 11362.772744545227, 11552.59565915189, 14037.5086871959, 14049.297535123407, 14078.225697586731, 14088.647783805487, 14112.788308175386, 7900.565468899151, 8005.479643823133, 8122.756317310372, 8182.4681395709085, 8327.20891515305, 7016.193137789118, 7949.7217597383815, 7962.840743185104, 8114.431989760778, 8115.594220288962, 3781.3949768036982, 4375.321825117112, 4393.989382441992, 4467.530909254547, 4546.158960592301, 1912.6652835753687, 1953.658109830774, 1959.8530110241504, 1960.4218604023613, 1963.646543622133, 975.9902400976041, 983.9163671088038, 992.7170756334555, 995.7659949911765, 1026.9024442677933, 15459.536213913601, 16264.0, 17969.3827555518, 19336.70994935077, 19627.509312267022, 983.2674657380297, 999.1307562420741, 1001.6744558018673, 1031.4327120083924, 1034.7206254311293, 8334.291585215324, 9067.95466022679, 10269.897301026895, 10325.432101234353, 10360.844587331045, 530787.0, 549813.0, 551044.7343289853, 551284.0, 551957.0, 97792.0, 98102.0, 100767.0, 120218.79781202076, 128553.0, 852.3011130872625, 1021.0708255487323, 1044.1333693034762, 1046.450613427941, 1050.0392141190725, 15429.922850385676, 16694.998300102034, 17364.82635173632, 17944.820551794837, 18117.728234076763, 51733.96532069413, 51823.963520730125, 52217.95564088773, 52332.0, 53931.0, 78086.0, 78168.0, 78553.60723196347, 79360.0, 80008.0, 7772.572508512086, 7856.332211762065, 7884.526928384315, 8028.397870159644, 8125.0, 9240.491772952284, 10538.947305263424, 11312.15158863072, 12304.507819687113, 12605.74788504243, 10323.380597164194, 10614.416207108683, 10703.25150753534, 10976.725581860517, 11003.944980275048, 10199.796004079728, 10223.948880255552, 10598.31110977784, 10649.201309901633, 10834.945825270823, 10824.296420732628, 10833.349999000086, 10932.562697492329, 11147.940945610155, 11177.273477223955, 7613.801880086153, 7636.274553917369, 7824.21757824213, 7861.606919654108, 7942.126366099609, 7896.0, 9034.774130646787, 9313.860292095489, 10594.417307048187, 10781.460926953465], "value": 9255.763214200284, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.7909355409586, 1234.648125284294, 1382.4193838587792, 1384.7853582694684, 1395.6022533577925, 6094.939050609492, 7735.651895664694, 7929.444938854283, 8320.667173313068, 8359.289460395874, 7848.921510784905, 8180.181981801832, 8232.588370581461, 8288.803467081214, 8308.875366869503, 2257.841951063422, 2307.8846057697133, 2308.272894038381, 2312.6877871487304, 2336.6261398176293, 2042.7548694156721, 2115.6826476028564, 2292.954140917189, 2328.2549584133085, 2350.764923507644, 2200.8459407841456, 2254.6505291679814, 2273.9772602274015, 2275.203678712454, 2298.0118549023914, 2076.1487790006177, 2199.670049492573, 2213.701150344699, 2215.745189303235, 2222.911083556664, 1992.531755037565, 2238.9029375606, 2265.728112626479, 2274.226762900622, 2274.8976296066717, 8164.6325915333955, 8367.99584049918, 8392.0, 8400.83198336036, 8401.36989725773, 4979.676321039157, 5136.743162841843, 5146.047981123466, 5148.742562871841, 5155.304033955405, 3338.749593780476, 3420.0765793235937, 3460.0, 3476.0, 3491.930161396783, 1167.048855183023, 1188.393919101256, 1200.9819852702128, 1224.2593231095148, 1244.352936473038, 10586.258961872672, 10597.470126493721, 10639.946800265949, 10651.147908167328, 10694.914491674126, 4079.286124928126, 4304.849330273425, 4325.783710814447, 4403.383526306319, 4438.112377524508, 7332.450066245053, 8004.679812807539, 8532.829343413097, 8533.359998000175, 8568.443051201652, 3902.35611124167, 4493.5281795411665, 4939.506049395103, 4952.876178095505, 4964.975175124174, 4081.795910204478, 4212.178625168062, 4604.976975115103, 4615.492295847454, 4697.976510117496, 3505.4741788731385, 3543.982280088583, 3569.321828852562, 3585.766925149857, 3625.6011838697723, 1064.9627263045757, 1097.846301517788, 1106.9557217711201, 1115.5816568786704, 1129.1362107987395, 732.7765031665286, 753.8756105242683, 769.461377036077, 789.0570767932265, 811.6631597886928, 5564.554835613141, 5577.135543990688, 5603.607747457639, 5607.831765047055, 5752.827415177551, 882.7219425880827, 928.3640706116332, 938.4275591888942, 967.3615413826841, 989.8762654668167, 3568.6431356864105, 3602.657747513982, 3696.5194524711615, 3760.4735337052834, 3836.808159592065, 9306.0, 9561.713148605553, 9848.704538863845, 9852.261080418997, 9890.307678462583, 9583.760405989906, 9823.410595364157, 9829.361091529028, 9834.606615735434, 9868.062534059252, 7581.241875812485, 7719.768406947801, 7792.532448053135, 7802.453828231969, 7804.141544429995, 4470.485894122087, 4623.953760462487, 5232.790688372423, 5386.35363756336, 5449.782008719607, 9048.592813323285, 9079.591418386057, 9154.221891139328, 9159.542022898962, 9261.398009129385, 9268.073192680678, 9268.53657317145, 9295.907040929775, 9351.953240233755, 9483.0, 5378.515933565841, 6608.942569189099, 6647.302033286435, 6701.564398314094, 6763.5941843489545, 7723.0, 7783.182765809508, 7793.844123117619, 7797.220277972157, 7820.530768153929, 6980.476464265302, 7082.716691332289, 7092.964535177498, 7107.538010029331, 7203.963980180066, 6861.279565645735, 6900.654967251718, 6912.0, 6965.547239429421, 6991.545549539263, 6177.598456100519, 6235.095911092775, 6321.146645202839, 6340.365963403623, 6350.396712312323, 4637.118947399983, 4706.0, 4737.763111844463, 4745.83389581363, 4851.029794041136, 4694.976525117352, 4776.904461910812, 4831.903361932812, 4834.371531700856, 4839.128956787812], "value": 4250.369672869482, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.2552151401179, 344.1074092491298, 362.9129009037831, 367.45799945081023, 369.08835177112525, 3940.625640564149, 4090.8568200113, 5235.397929238139, 5341.839744807652, 5492.203630473579, 4406.449193850769, 5013.874653133674, 5032.220005899082, 5233.0, 5313.57491400688, 3177.0, 4160.895977600561, 4161.897097269226, 4202.663786897041, 4220.240356735786, 3271.7382609391193, 3733.551973763152, 3826.4069069294305, 4037.1925614876995, 4082.6937979651498, 3357.8992630221132, 3411.761176717631, 4103.05629705168, 4118.341065429532, 4130.8554200602985, 4100.528439229497, 4120.475424093105, 4126.319157339036, 4126.8142933567915, 4137.110521237948, 2793.4971705093076, 3196.488561830119, 3637.363461394272, 3705.7961812100325, 3720.7395482316247, 4230.773075808042, 5054.9241761373605, 5076.289319495274, 5115.693058416507, 5155.628410930976, 4729.074867859668, 4753.928691069636, 4758.690685105457, 4768.141734487791, 4777.5700186982995, 4343.413639158704, 4865.9756701216265, 5553.972230138864, 5590.776368945238, 5655.0, 3542.557180352456, 3881.9805900970596, 4007.8196481158416, 4031.23406552754, 4040.979795101035, 4625.866662667634, 5597.992361374951, 5825.88348233033, 5840.853978650525, 5851.795187168429, 3285.0, 3493.1441796759955, 3948.624880636335, 4125.0, 4131.35963925592, 3796.8101594920145, 3825.062859599416, 3988.940165897514, 4132.772697501606, 4233.661307095425, 3854.69162467002, 4451.421315229064, 4664.603508701731, 4739.786709598076, 4800.543948324904, 5646.068398714248, 5661.405552417019, 5697.23982171773, 5716.514096301861, 5741.66504289259, 1100.4112799652148, 1187.7149484123747, 1233.9506419743288, 1238.4674589926237, 1273.3951373097705, 888.9688860889967, 904.8461761500429, 916.9449833010041, 964.3249725192253, 964.9614015439304, 4241.639460645818, 4595.425571803525, 4848.902677237728, 5019.322391477104, 5032.245163225472, 5004.679055021862, 5415.160650099241, 5448.727563621803, 5471.452854714496, 5506.339239291311, 4364.8472303469225, 4717.0, 4961.875953101129, 5006.424261209934, 5044.369453818273, 2632.815702900817, 2659.002873922279, 2862.470442968058, 2865.584490248902, 2876.3528206153446, 4728.858134255978, 5198.766055527511, 5239.266502689627, 5560.248521715646, 5604.674060264944, 4635.0, 4761.025098231108, 5256.763445644955, 5472.015037293326, 5569.832905012856, 4661.340847434747, 5281.762320695655, 5342.984832881818, 5616.241807356118, 5622.97188514063, 4895.559399654048, 5056.266841308064, 5132.922086361906, 5368.423156884795, 5442.972785136208, 5068.82259120929, 5281.683099014072, 5302.70835104073, 5328.413874473727, 5407.756650950638, 5403.433369149622, 5418.882158073744, 5616.241807356118, 5618.85952851182, 5627.52166065889, 3231.967680323167, 3467.618561958233, 3717.2343137009702, 3874.612538746103, 3900.8049597519444, 4292.943168705543, 5031.371078615173, 5094.497123348547, 5423.864403389947, 5455.863603409946, 4589.426321709786, 4725.432948046119, 5239.476052394883, 5256.028989129077, 5276.366835979707, 4344.631441096045, 4668.836590719308, 4715.24556071027, 5083.87290317745, 5232.659877107976, 5327.840164795062, 5366.919496207638, 5509.834704958857, 5512.39363670004, 5528.80649177293, 4779.569838714532, 4845.127876982177, 4946.505349465025, 5596.244506991504, 5615.971920140537, 5168.53483186515, 5435.673859568438, 5480.2875626169935, 5483.2323474712775, 5496.340439147168], "value": 3980.8746497587667, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.9688015599222, 654.5810681164053, 750.4559194583927, 790.4308897593733, 831.293400609482, 14330.28348582572, 15625.687486250268, 15833.445829395945, 15934.362625494996, 15967.840321596766, 13975.580732578013, 14000.0, 14370.784438233408, 14465.927670361634, 14530.273486325668, 884.4472204871955, 899.2491269789733, 902.7336935604012, 903.1239697493421, 904.1275169461468, 704.2218348724663, 728.8505856299446, 860.6815478273048, 886.7827382291348, 901.2744740483907, 964.9517524123765, 966.3284017607765, 969.966985160805, 974.4202199691173, 977.7457860956167, 948.1798244518511, 951.5908159491416, 963.1379914976058, 963.1572374172599, 964.2671569607114, 669.5012215899137, 715.1096884378982, 719.8020544350293, 723.5080145501057, 724.6702750248625, 13573.796393054112, 13753.518626848112, 14989.400423983137, 14998.400063997427, 15527.301271442811, 12161.391930403444, 14038.719225615532, 14269.928650356682, 14353.856461435356, 14442.35009424568, 10106.444145572064, 11450.656480305604, 11568.363739994296, 11574.942125289319, 12114.697132571668, 3691.868674107863, 3716.2381711749026, 3727.310447567209, 3789.4694742736037, 3862.748921320105, 79464.20535794625, 96722.0, 97399.0, 98185.0, 99012.99999999927, 10864.456777161111, 10958.726031849108, 11005.944970275257, 11151.107911367071, 11281.435928203557, 22387.440313991956, 22555.661665075037, 22566.87165641711, 22640.981155848025, 22667.319980400614, 7679.462437629312, 7738.0, 7758.224177582197, 7785.0657921048705, 7934.246246606564, 4207.726497777696, 4241.1093670328955, 4241.300185469363, 4251.59609837065, 4303.784810759449, 3501.8249087546033, 3694.261147770457, 3881.437191607258, 3885.514310711161, 3961.4057891316534, 679.5718697220719, 682.2801943949181, 687.1547995964991, 694.8783962806489, 701.7824474412948, 611.2267981004034, 628.1614045249552, 630.7477009196358, 632.2476253258608, 653.9574927629784, 8554.0, 8674.56627168639, 9176.128267814547, 9351.672691455766, 9472.857907131398, 603.2640178981635, 615.2985596419995, 619.8822223777468, 626.27352271365, 627.5983370642792, 4396.956030439718, 4425.822967081345, 4647.907041859144, 4689.882752931204, 4733.810647574059, 89354.0, 95885.0, 106020.0, 106147.0, 107304.0, 66715.3328466709, 67067.99398009034, 76153.0, 91221.54389228011, 93457.532712336, 76279.61860190664, 77430.0, 78639.0, 78994.0, 79931.60034199792, 9775.21798256152, 9778.951105244429, 9966.800663986534, 9977.551010204414, 9987.500624968869, 32586.0, 46424.0, 49068.52794416173, 49270.0, 50849.49150508447, 69651.60696786137, 69703.65148174227, 70051.0, 70297.29702702907, 70673.64663176652, 12705.809412859, 12991.545295914597, 13005.869941300465, 13266.86733132695, 13363.198208107544, 14520.0, 14608.0, 14690.779638305221, 15000.399984000518, 15387.846121539089, 13317.533886313933, 13683.657908552366, 14011.929940350234, 14127.64680882945, 14216.933729970087, 13086.345682716017, 13402.396892139683, 13580.524681636096, 13742.175469471864, 13948.02363834522, 13418.865811342152, 13441.975555499934, 13510.729785404434, 13571.59285221445, 13948.442062317394, 9868.851967220355, 10288.742781430525, 10564.0, 11238.84162859747, 11415.143864210051, 9070.909290907006, 9376.249900007846, 10810.67567972962, 10830.40432776205, 10874.728131796768], "value": 7439.572079368721, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 119.0, 118.0, 118.0, 116.0, 117.0, 140.0, 116.0, 117.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 114.0, 112.0, 145.0, 137.0, 142.0, 146.0, 118.0, 137.0, 115.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 216.0, 209.0, 205.0, 195.0, 201.0, 198.0, 198.0, 199.0, 278.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 60.0, 59.0, 89.0, 62.0, 58.0, 59.0, 60.0, 74.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 85.0, 75.0, 100.0, 93.0, 85.0, 72.0, 73.0, 87.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 122.0, 88.0, 87.0, 112.0, 79.0, 135.0, 130.0, 108.0, 86.0], "value": 107.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 153.0, 95.0, 94.0, 96.0, 96.0, 119.0, 96.0, 96.0], "value": 103.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 83.0, 147.0, 144.0, 111.0, 96.0, 91.0, 92.0, 95.0], "value": 104.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 61.0, 63.0, 63.0, 62.0, 63.0, 64.0, 61.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 83.0, 77.0, 75.0, 72.0, 72.0, 73.0, 73.0, 74.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 105.0, 112.0, 113.0, 88.0, 89.0, 91.0, 86.0, 88.0, 88.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 163.0, 140.0, 143.0, 140.0, 143.0, 142.0, 149.0, 215.0, 172.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 169.0, 174.0, 176.0, 174.0, 175.0, 177.0, 171.0, 171.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 86.0, 68.0, 69.0, 70.0, 70.0, 68.0, 69.0, 67.0, 68.0], "value": 70.5, "unit": "score"}]}]} 17:05:37 INFO - Return code: 0 17:05:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:05:37 ERROR - # TBPL SUCCESS # 17:05:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:37 INFO - 2016-12-28 17:05:37.753 screenresolution[1977:12609] starting screenresolution argv=screenresolution get 17:05:37 INFO - 2016-12-28 17:05:37.756 screenresolution[1977:12609] Display 0: 1600x1200x32@60 17:05:37 INFO - 2016-12-28 17:05:37.762 screenresolution[1978:12612] starting screenresolution argv=screenresolution list 17:05:37 INFO - Available Modes on Display 0 17:05:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:37 INFO - Intel Iris: 17:05:37 INFO - Chipset Model: Intel Iris 17:05:37 INFO - Type: GPU 17:05:37 INFO - Bus: Built-In 17:05:37 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:37 INFO - Vendor: Intel (0x8086) 17:05:37 INFO - Device ID: 0x0a2e 17:05:37 INFO - Revision ID: 0x0009 17:05:37 INFO - Displays: 17:05:37 INFO - Display: 17:05:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:37 INFO - Display Serial Number: 1600x1200 60 17:05:37 INFO - Main Display: Yes 17:05:37 INFO - Mirror: Off 17:05:37 INFO - Online: Yes 17:05:37 INFO - Rotation: Supported 17:05:37 INFO - Return code: 0 17:05:37 INFO - Running post-action listener: _resource_record_post_action 17:05:37 INFO - [mozharness: 2016-12-29 01:05:37.997056Z] Finished run-tests step (success) 17:05:37 INFO - Running post-run listener: _resource_record_post_run 17:05:38 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 1183744; Write bytes: 554580992; Read time: 15; Write time: 1904 17:05:38 INFO - TinderboxPrint: CPU usage
54.7% 17:05:38 INFO - TinderboxPrint: I/O read bytes / time
1,183,744 / 15 17:05:38 INFO - TinderboxPrint: I/O write bytes / time
554,580,992 / 1,904 17:05:38 INFO - TinderboxPrint: CPU idle
2,174.2 (45.3%) 17:05:38 INFO - TinderboxPrint: CPU system
969.0 (20.2%) 17:05:38 INFO - TinderboxPrint: CPU user
1,658.4 (34.5%) 17:05:38 INFO - TinderboxPrint: Swap in / out
250,720,256 / 0 17:05:38 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228424192; Write bytes: 223512576; Read time: 16521; Write time: 520 17:05:38 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 1183744; Write bytes: 301990912; Read time: 15; Write time: 1329 17:05:38 INFO - Running post-run listener: _upload_blobber_files 17:05:38 INFO - Blob upload gear active. 17:05:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:05:38 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:05:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:05:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:39 INFO - (blobuploader) - INFO - Done attempting. 17:05:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:05:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:05:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:40 INFO - (blobuploader) - INFO - Done attempting. 17:05:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:05:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:05:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:41 INFO - (blobuploader) - INFO - Done attempting. 17:05:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:05:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:44 INFO - (blobuploader) - INFO - Done attempting. 17:05:44 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:44 INFO - Return code: 0 17:05:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5655c2d0a069541fdca5493d7259ea69fff2dad1ee6fca0f5463bc7257808b7cbcba021c9e5abebc453e372318c89ca43721137ee8150e15ddc8fae937ed4bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde1517f4aa1ad5a772ec430c514fa931f0d2c9877f7a2bbd9bc35ba64134aac70594a6102c1f9f1b44d29688441c0e0747630cd4ee5290ca635f889bfb604a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea107e0b9f2983b9bc7549d98e0a438eafe4465f056c60fd0e25b899596022f941b394e7b312d825b985c327c61f67709c86331cb32c18b046901ac3144a6479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/228a813c12418cca1ae511993357c5287bca7d03f0b53c2bce31ddfa2dba4d156fe9e21371b06434bd0dda17e3d88b9dd05f19a39e24dc3a8c8a22b63e593475"} 17:05:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:44 INFO - Contents: 17:05:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5655c2d0a069541fdca5493d7259ea69fff2dad1ee6fca0f5463bc7257808b7cbcba021c9e5abebc453e372318c89ca43721137ee8150e15ddc8fae937ed4bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde1517f4aa1ad5a772ec430c514fa931f0d2c9877f7a2bbd9bc35ba64134aac70594a6102c1f9f1b44d29688441c0e0747630cd4ee5290ca635f889bfb604a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea107e0b9f2983b9bc7549d98e0a438eafe4465f056c60fd0e25b899596022f941b394e7b312d825b985c327c61f67709c86331cb32c18b046901ac3144a6479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/228a813c12418cca1ae511993357c5287bca7d03f0b53c2bce31ddfa2dba4d156fe9e21371b06434bd0dda17e3d88b9dd05f19a39e24dc3a8c8a22b63e593475"} 17:05:44 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:44 INFO - Copying logs to upload dir... 17:05:44 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.022347 ========= master_lag: 0.56 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-28 17:05:45.129880) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:45.133169) ========= 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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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/5655c2d0a069541fdca5493d7259ea69fff2dad1ee6fca0f5463bc7257808b7cbcba021c9e5abebc453e372318c89ca43721137ee8150e15ddc8fae937ed4bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde1517f4aa1ad5a772ec430c514fa931f0d2c9877f7a2bbd9bc35ba64134aac70594a6102c1f9f1b44d29688441c0e0747630cd4ee5290ca635f889bfb604a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea107e0b9f2983b9bc7549d98e0a438eafe4465f056c60fd0e25b899596022f941b394e7b312d825b985c327c61f67709c86331cb32c18b046901ac3144a6479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/228a813c12418cca1ae511993357c5287bca7d03f0b53c2bce31ddfa2dba4d156fe9e21371b06434bd0dda17e3d88b9dd05f19a39e24dc3a8c8a22b63e593475"} build_url:https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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/5655c2d0a069541fdca5493d7259ea69fff2dad1ee6fca0f5463bc7257808b7cbcba021c9e5abebc453e372318c89ca43721137ee8150e15ddc8fae937ed4bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fde1517f4aa1ad5a772ec430c514fa931f0d2c9877f7a2bbd9bc35ba64134aac70594a6102c1f9f1b44d29688441c0e0747630cd4ee5290ca635f889bfb604a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea107e0b9f2983b9bc7549d98e0a438eafe4465f056c60fd0e25b899596022f941b394e7b312d825b985c327c61f67709c86331cb32c18b046901ac3144a6479", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/228a813c12418cca1ae511993357c5287bca7d03f0b53c2bce31ddfa2dba4d156fe9e21371b06434bd0dda17e3d88b9dd05f19a39e24dc3a8c8a22b63e593475"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:45.168331) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:45.168715) ========= 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.HxivbWiuSp/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.e9C2JIkgdZ/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.005335 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:45.218072) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 17:05:45.218416) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 17:05:45.218898) ========= ========= Total master_lag: 0.73 =========