builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0181 starttime: 1480807006.95 results: success (0) buildid: 20161203133838 builduid: 007af99f81c14ab89a2e6ca6d59353ca revision: 11d06cafe634dfa7981cfdca62c0b34d04a22a84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:46.952790) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:46.953176) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:46.953467) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:47.031915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:47.032198) ========= 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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-03 15:16:47-- 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% 173M=0s 2016-12-03 15:16:47 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.649764 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:47.707729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:47.708031) ========= 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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.027815 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:47.756833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-03 15:16:47.757160) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 11d06cafe634dfa7981cfdca62c0b34d04a22a84 --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 11d06cafe634dfa7981cfdca62c0b34d04a22a84 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hwyg9S44Ky/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.qkyQcsHXzd/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-03 15:16:47,847 truncating revision to first 12 chars 2016-12-03 15:16:47,847 Setting DEBUG logging. 2016-12-03 15:16:47,847 attempt 1/10 2016-12-03 15:16:47,847 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/11d06cafe634?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-03 15:16:48,822 unpacking tar archive at: mozilla-central-11d06cafe634/testing/mozharness/ program finished with exit code 0 elapsedTime=1.244130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-03 15:16:49.024457) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:49.024762) ========= script_repo_revision: 11d06cafe634dfa7981cfdca62c0b34d04a22a84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:49.025181) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:49.025473) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 15:16:49.045331) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-03 15:16:49.045603) ========= /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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:16:49 INFO - MultiFileLogger online at 20161203 15:16:49 in /builds/slave/test 15:16:49 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 15:16:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:16:49 INFO - {'append_to_log': False, 15:16:49 INFO - 'base_work_dir': '/builds/slave/test', 15:16:49 INFO - 'blob_upload_branch': 'Firefox', 15:16:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:16:49 INFO - 'branch': 'Firefox', 15:16:49 INFO - 'buildbot_json_path': 'buildprops.json', 15:16:49 INFO - 'config_files': ('talos/mac_config.py',), 15:16:49 INFO - 'default_actions': ('clobber', 15:16:49 INFO - 'read-buildbot-config', 15:16:49 INFO - 'download-and-extract', 15:16:49 INFO - 'populate-webroot', 15:16:49 INFO - 'create-virtualenv', 15:16:49 INFO - 'install', 15:16:49 INFO - 'run-tests'), 15:16:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:16:49 INFO - 'download_minidump_stackwalk': True, 15:16:49 INFO - 'download_symbols': 'ondemand', 15:16:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:16:49 INFO - 'tooltool.py': '/tools/tooltool.py', 15:16:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:16:49 INFO - '/tools/misc-python/virtualenv.py')}, 15:16:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:16:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:16:49 INFO - 'installer_path': 'installer.exe', 15:16:49 INFO - 'log_level': 'info', 15:16:49 INFO - 'log_name': 'talos', 15:16:49 INFO - 'log_to_console': True, 15:16:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:16:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:16:49 INFO - 'opt_config_files': (), 15:16:49 INFO - 'pip_index': False, 15:16:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:16:49 INFO - 'cmd': ('bash', 15:16:49 INFO - '-c', 15:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:16:49 INFO - 'enabled': True, 15:16:49 INFO - 'halt_on_failure': False, 15:16:49 INFO - 'name': 'check_screen_resolution'},), 15:16:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:16:49 INFO - 'cmd': ('bash', 15:16:49 INFO - '-c', 15:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:16:49 INFO - 'enabled': True, 15:16:49 INFO - 'halt_on_failure': False, 15:16:49 INFO - 'name': 'check_screen_resolution'},), 15:16:49 INFO - 'run_cmd_checks_enabled': True, 15:16:49 INFO - 'sps_profile': False, 15:16:49 INFO - 'sps_profile_interval': 0, 15:16:49 INFO - 'suite': 'dromaeojs-e10s', 15:16:49 INFO - 'system_bits': '32', 15:16:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:16:49 INFO - 'title': 't-yosemite-r7-0181', 15:16:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:16:49 INFO - 'use_talos_json': True, 15:16:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:16:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:16:49 INFO - 'work_dir': 'build'} 15:16:49 INFO - [mozharness: 2016-12-03 23:16:49.246383Z] Running clobber step. 15:16:49 INFO - Running pre-action listener: _resource_record_pre_action 15:16:49 INFO - Running main action method: clobber 15:16:49 INFO - rmtree: /builds/slave/test/build 15:16:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:16:51 INFO - Running post-action listener: _resource_record_post_action 15:16:51 INFO - [mozharness: 2016-12-03 23:16:51.553739Z] Finished clobber step (success) 15:16:51 INFO - [mozharness: 2016-12-03 23:16:51.553877Z] Running read-buildbot-config step. 15:16:51 INFO - Running pre-action listener: _resource_record_pre_action 15:16:51 INFO - Running main action method: read_buildbot_config 15:16:51 INFO - Using buildbot properties: 15:16:51 INFO - { 15:16:51 INFO - "project": "", 15:16:51 INFO - "product": "firefox", 15:16:51 INFO - "script_repo_revision": "production", 15:16:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:16:51 INFO - "repository": "", 15:16:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:16:51 INFO - "buildid": "20161203133838", 15:16:51 INFO - "pgo_build": "False", 15:16:51 INFO - "basedir": "/builds/slave/test", 15:16:51 INFO - "buildnumber": 5, 15:16:51 INFO - "slavename": "t-yosemite-r7-0181", 15:16:51 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:16:51 INFO - "platform": "yosemite_r7", 15:16:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:16:51 INFO - "branch": "mozilla-central", 15:16:51 INFO - "repo_path": "mozilla-central", 15:16:51 INFO - "revision": "11d06cafe634dfa7981cfdca62c0b34d04a22a84", 15:16:51 INFO - "stage_platform": "macosx64", 15:16:51 INFO - "builduid": "007af99f81c14ab89a2e6ca6d59353ca", 15:16:51 INFO - "slavebuilddir": "test" 15:16:51 INFO - } 15:16:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:16:51 INFO - Running post-action listener: _resource_record_post_action 15:16:51 INFO - [mozharness: 2016-12-03 23:16:51.558076Z] Finished read-buildbot-config step (success) 15:16:51 INFO - [mozharness: 2016-12-03 23:16:51.558179Z] Running download-and-extract step. 15:16:51 INFO - Running pre-action listener: _resource_record_pre_action 15:16:51 INFO - Running main action method: download_and_extract 15:16:51 INFO - mkdir: /builds/slave/test/build/tests 15:16:51 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')]} 15:16:51 INFO - trying https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:16:51 INFO - Downloading https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/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 15:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/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 15:16:52 INFO - Downloaded 1199 bytes. 15:16:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:16:52 INFO - Using the following test package requirements: 15:16:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:16:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:16:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:16:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'jsshell-mac.zip'], 15:16:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:16:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:16:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:16:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:16:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:16:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:16:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:16:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:16:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:16:52 INFO - Fetch https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:16:53 INFO - Expected file size: 16283757 15:16:53 INFO - Obtained file size: 16283757 15:17:00 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 15:17:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:17:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:17:00 INFO - Fetch https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:17:01 INFO - Expected file size: 16283757 15:17:01 INFO - Obtained file size: 16283757 15:17:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:17:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:17:07 INFO - Fetch https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:17:09 INFO - Expected file size: 14409734 15:17:09 INFO - Obtained file size: 14409734 15:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:09 INFO - trying https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:17:09 INFO - Downloading https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:17:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/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 15:17:12 INFO - Downloaded 107603955 bytes. 15:17:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:17:12 INFO - mkdir: /builds/slave/test/properties 15:17:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:17:12 INFO - Writing to file /builds/slave/test/properties/build_url 15:17:12 INFO - Contents: 15:17:12 INFO - build_url:https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:17:12 INFO - Running post-action listener: _resource_record_post_action 15:17:12 INFO - Running post-action listener: set_extra_try_arguments 15:17:12 INFO - [mozharness: 2016-12-03 23:17:12.745397Z] Finished download-and-extract step (success) 15:17:12 INFO - [mozharness: 2016-12-03 23:17:12.745531Z] Running populate-webroot step. 15:17:12 INFO - Running pre-action listener: _resource_record_pre_action 15:17:12 INFO - Running main action method: populate_webroot 15:17:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:17:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:17:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:17:12 INFO - '--tppagecycles', 15:17:12 INFO - '7'], 15:17:12 INFO - 'tests': ['tsvgm']}}, 15:17:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:17:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:17:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:17:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:17:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:17:12 INFO - 'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:17:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:17:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:17:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:17:12 INFO - 'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['damp', 'tps']}, 15:17:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:17:12 INFO - 'tests': ['damp', 'tps']}, 15:17:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['dromaeo_dom']}, 15:17:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:17:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['basic_compositor_video']}, 15:17:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:17:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['a11yr', 15:17:12 INFO - 'ts_paint', 15:17:12 INFO - 'tpaint', 15:17:12 INFO - 'sessionrestore', 15:17:12 INFO - 'sessionrestore_no_auto_restore', 15:17:12 INFO - 'tabpaint']}, 15:17:12 INFO - 'other-e10s': {'tests': ['a11yr', 15:17:12 INFO - 'ts_paint', 15:17:12 INFO - 'tpaint', 15:17:12 INFO - 'sessionrestore', 15:17:12 INFO - 'sessionrestore_no_auto_restore', 15:17:12 INFO - 'tabpaint']}, 15:17:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:17:12 INFO - 'ts_paint', 15:17:12 INFO - 'tpaint', 15:17:12 INFO - 'sessionrestore', 15:17:12 INFO - 'sessionrestore_no_auto_restore', 15:17:12 INFO - 'tabpaint']}, 15:17:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:17:12 INFO - 'ts_paint', 15:17:12 INFO - 'tpaint', 15:17:12 INFO - 'sessionrestore', 15:17:12 INFO - 'sessionrestore_no_auto_restore', 15:17:12 INFO - 'tabpaint']}, 15:17:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['a11yr', 15:17:12 INFO - 'ts_paint', 15:17:12 INFO - 'tpaint', 15:17:12 INFO - 'sessionrestore', 15:17:12 INFO - 'sessionrestore_no_auto_restore', 15:17:12 INFO - 'tabpaint']}, 15:17:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['a11yr', 15:17:12 INFO - 'ts_paint', 15:17:12 INFO - 'tpaint', 15:17:12 INFO - 'sessionrestore', 15:17:12 INFO - 'sessionrestore_no_auto_restore', 15:17:12 INFO - 'tabpaint']}, 15:17:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['tsvgx', 15:17:12 INFO - 'tsvgr_opacity', 15:17:12 INFO - 'tart', 15:17:12 INFO - 'tscrollx', 15:17:12 INFO - 'cart', 15:17:12 INFO - 'tsvg_static']}, 15:17:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:17:12 INFO - 'tsvgr_opacity', 15:17:12 INFO - 'tart', 15:17:12 INFO - 'tscrollx', 15:17:12 INFO - 'cart', 15:17:12 INFO - 'tsvg_static']}, 15:17:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:17:12 INFO - 'talos_options': ['--disable-e10s'], 15:17:12 INFO - 'tests': ['tp5o']}, 15:17:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:17:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:17:12 INFO - 'talos_options': ['--disable-e10s', 15:17:12 INFO - '--xperf_path', 15:17:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:17:12 INFO - 'tests': ['tp5n']}, 15:17:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:17:12 INFO - 'talos_options': ['--xperf_path', 15:17:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:17:12 INFO - 'tests': ['tp5n']}}, 15:17:12 INFO - 'talos.zip': {'path': '', 15:17:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:17:12 INFO - Running post-action listener: _resource_record_post_action 15:17:12 INFO - [mozharness: 2016-12-03 23:17:12.764611Z] Finished populate-webroot step (success) 15:17:12 INFO - [mozharness: 2016-12-03 23:17:12.764744Z] Running create-virtualenv step. 15:17:12 INFO - Running pre-action listener: _resource_record_pre_action 15:17:12 INFO - Running main action method: create_virtualenv 15:17:12 INFO - Creating virtualenv /builds/slave/test/build/venv 15:17:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:17:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:17:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:17:12 INFO - Using real prefix '/tools/python27' 15:17:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:17:13 INFO - Installing distribute.............................................................................................................................................................................................done. 15:17:16 INFO - Installing pip.................done. 15:17:16 INFO - Return code: 0 15:17:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:17:16 INFO - Reading from file tmpfile_stdout 15:17:16 INFO - Output received: 15:17:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:17:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:16 INFO - 'HOME': '/Users/cltbld', 15:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:16 INFO - 'LOGNAME': 'cltbld', 15:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:16 INFO - 'MOZ_NO_REMOTE': '1', 15:17:16 INFO - 'NO_EM_RESTART': '1', 15:17:16 INFO - 'PAGER': '/bin/cat', 15:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:16 INFO - 'PWD': '/builds/slave/test', 15:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:16 INFO - 'SHELL': '/bin/bash', 15:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:16 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:16 INFO - 'USER': 'cltbld', 15:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:16 INFO - 'XPC_FLAGS': '0x0', 15:17:16 INFO - 'XPC_SERVICE_NAME': '0', 15:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:17:16 INFO - Cleaning up... 15:17:17 INFO - Return code: 0 15:17:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:17 INFO - 'HOME': '/Users/cltbld', 15:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:17 INFO - 'LOGNAME': 'cltbld', 15:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:17 INFO - 'MOZ_NO_REMOTE': '1', 15:17:17 INFO - 'NO_EM_RESTART': '1', 15:17:17 INFO - 'PAGER': '/bin/cat', 15:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:17 INFO - 'PWD': '/builds/slave/test', 15:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:17 INFO - 'SHELL': '/bin/bash', 15:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:17 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:17 INFO - 'USER': 'cltbld', 15:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:17 INFO - 'XPC_FLAGS': '0x0', 15:17:17 INFO - 'XPC_SERVICE_NAME': '0', 15:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:17 INFO - Downloading/unpacking psutil>=3.1.1 15:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:17:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:17:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:17:21 INFO - Installing collected packages: psutil 15:17:21 INFO - Running setup.py install for psutil 15:17:21 INFO - building 'psutil._psutil_osx' extension 15:17:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:17:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:17:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:17:22 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 15:17:22 INFO - building 'psutil._psutil_posix' extension 15:17:22 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 15:17:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:17:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:17:22 INFO - ^ 15:17:22 INFO - 1 warning generated. 15:17:22 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 15:17:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:17:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:17:22 INFO - Successfully installed psutil 15:17:22 INFO - Cleaning up... 15:17:22 INFO - Return code: 0 15:17:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:22 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 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:22 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 15:17:22 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 15:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:22 INFO - 'HOME': '/Users/cltbld', 15:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:22 INFO - 'LOGNAME': 'cltbld', 15:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:22 INFO - 'MOZ_NO_REMOTE': '1', 15:17:22 INFO - 'NO_EM_RESTART': '1', 15:17:22 INFO - 'PAGER': '/bin/cat', 15:17:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:22 INFO - 'PWD': '/builds/slave/test', 15:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:22 INFO - 'SHELL': '/bin/bash', 15:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:22 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:22 INFO - 'USER': 'cltbld', 15:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:22 INFO - 'XPC_FLAGS': '0x0', 15:17:22 INFO - 'XPC_SERVICE_NAME': '0', 15:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:17:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:17:25 INFO - Installing collected packages: mozsystemmonitor 15:17:25 INFO - Running setup.py install for mozsystemmonitor 15:17:25 INFO - Successfully installed mozsystemmonitor 15:17:25 INFO - Cleaning up... 15:17:25 INFO - Return code: 0 15:17:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:25 INFO - 'HOME': '/Users/cltbld', 15:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:25 INFO - 'LOGNAME': 'cltbld', 15:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:25 INFO - 'MOZ_NO_REMOTE': '1', 15:17:25 INFO - 'NO_EM_RESTART': '1', 15:17:25 INFO - 'PAGER': '/bin/cat', 15:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:25 INFO - 'PWD': '/builds/slave/test', 15:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:25 INFO - 'SHELL': '/bin/bash', 15:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:25 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:25 INFO - 'USER': 'cltbld', 15:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:25 INFO - 'XPC_FLAGS': '0x0', 15:17:25 INFO - 'XPC_SERVICE_NAME': '0', 15:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:25 INFO - Downloading/unpacking jsonschema==2.5.1 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:17:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:28 INFO - Downloading functools32-3.2.3-2.tar.gz 15:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:17:28 INFO - warning: no files found matching '*.txt' 15:17:28 INFO - no previously-included directories found matching 'build' 15:17:28 INFO - no previously-included directories found matching 'dist' 15:17:28 INFO - no previously-included directories found matching '.git*' 15:17:28 INFO - Installing collected packages: jsonschema, functools32 15:17:28 INFO - Running setup.py install for functools32 15:17:29 INFO - warning: no files found matching '*.txt' 15:17:29 INFO - no previously-included directories found matching 'build' 15:17:29 INFO - no previously-included directories found matching 'dist' 15:17:29 INFO - no previously-included directories found matching '.git*' 15:17:29 INFO - Successfully installed jsonschema functools32 15:17:29 INFO - Cleaning up... 15:17:29 INFO - Return code: 0 15:17:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:29 INFO - 'HOME': '/Users/cltbld', 15:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:29 INFO - 'LOGNAME': 'cltbld', 15:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:29 INFO - 'MOZ_NO_REMOTE': '1', 15:17:29 INFO - 'NO_EM_RESTART': '1', 15:17:29 INFO - 'PAGER': '/bin/cat', 15:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:29 INFO - 'PWD': '/builds/slave/test', 15:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:29 INFO - 'SHELL': '/bin/bash', 15:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:29 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:29 INFO - 'USER': 'cltbld', 15:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:29 INFO - 'XPC_FLAGS': '0x0', 15:17:29 INFO - 'XPC_SERVICE_NAME': '0', 15:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:17:29 INFO - Cleaning up... 15:17:29 INFO - Return code: 0 15:17:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:29 INFO - 'HOME': '/Users/cltbld', 15:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:29 INFO - 'LOGNAME': 'cltbld', 15:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:29 INFO - 'MOZ_NO_REMOTE': '1', 15:17:29 INFO - 'NO_EM_RESTART': '1', 15:17:29 INFO - 'PAGER': '/bin/cat', 15:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:29 INFO - 'PWD': '/builds/slave/test', 15:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:29 INFO - 'SHELL': '/bin/bash', 15:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:29 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:29 INFO - 'USER': 'cltbld', 15:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:29 INFO - 'XPC_FLAGS': '0x0', 15:17:29 INFO - 'XPC_SERVICE_NAME': '0', 15:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:29 INFO - Downloading/unpacking blobuploader==1.2.4 15:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:32 INFO - Downloading blobuploader-1.2.4.tar.gz 15:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:17:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:17:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17: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 15:17:33 INFO - Downloading docopt-0.6.1.tar.gz 15:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:17:33 INFO - Installing collected packages: blobuploader, requests, docopt 15:17:33 INFO - Running setup.py install for blobuploader 15:17:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:17:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:17:33 INFO - Running setup.py install for requests 15:17:34 INFO - Running setup.py install for docopt 15:17:34 INFO - Successfully installed blobuploader requests docopt 15:17:34 INFO - Cleaning up... 15:17:34 INFO - Return code: 0 15:17:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:34 INFO - 'HOME': '/Users/cltbld', 15:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:34 INFO - 'LOGNAME': 'cltbld', 15:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:34 INFO - 'MOZ_NO_REMOTE': '1', 15:17:34 INFO - 'NO_EM_RESTART': '1', 15:17:34 INFO - 'PAGER': '/bin/cat', 15:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:34 INFO - 'PWD': '/builds/slave/test', 15:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:34 INFO - 'SHELL': '/bin/bash', 15:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:34 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:34 INFO - 'USER': 'cltbld', 15:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:34 INFO - 'XPC_FLAGS': '0x0', 15:17:34 INFO - 'XPC_SERVICE_NAME': '0', 15:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-7qpQC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-_KML4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-s2nK36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-tY6dEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Gu9peE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-PzGs0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-d8GhuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-uflhLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-rPo7TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-gJOILl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-rK2VGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-faR3x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-2em_aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5GqBDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:36 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-VLrchL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:36 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mBKXCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:36 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bxLsmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:17:36 INFO - Running setup.py install for manifestparser 15:17:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:17:36 INFO - Running setup.py install for mozcrash 15:17:36 INFO - Running setup.py install for mozdebug 15:17:36 INFO - Running setup.py install for mozdevice 15:17:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:17:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Running setup.py install for mozfile 15:17:37 INFO - Running setup.py install for mozhttpd 15:17:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Running setup.py install for mozinfo 15:17:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Running setup.py install for mozInstall 15:17:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Running setup.py install for mozleak 15:17:37 INFO - Running setup.py install for mozlog 15:17:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:17:37 INFO - Running setup.py install for moznetwork 15:17:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:17:38 INFO - Running setup.py install for mozprocess 15:17:38 INFO - Running setup.py install for mozprofile 15:17:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:17:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:17:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:17:38 INFO - Running setup.py install for mozrunner 15:17:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:17:38 INFO - Running setup.py install for mozscreenshot 15:17:38 INFO - Running setup.py install for moztest 15:17:38 INFO - Running setup.py install for mozversion 15:17:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:17:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:17:38 INFO - Cleaning up... 15:17:38 INFO - Return code: 0 15:17:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:38 INFO - 'HOME': '/Users/cltbld', 15:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:38 INFO - 'LOGNAME': 'cltbld', 15:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:38 INFO - 'MOZ_NO_REMOTE': '1', 15:17:38 INFO - 'NO_EM_RESTART': '1', 15:17:38 INFO - 'PAGER': '/bin/cat', 15:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:38 INFO - 'PWD': '/builds/slave/test', 15:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:38 INFO - 'SHELL': '/bin/bash', 15:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:38 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:38 INFO - 'USER': 'cltbld', 15:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:38 INFO - 'XPC_FLAGS': '0x0', 15:17:38 INFO - 'XPC_SERVICE_NAME': '0', 15:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-nSR1XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bIyjDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-l16YSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-XSUB40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-MA8O1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-uhU28h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-UnRaor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-hRNS3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17:40 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)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-AhklK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-45edd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Tp72yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-q47hwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-e1AXV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5RC7NK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-wb4rV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-0UTqh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17:40 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)) 15:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:40 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-fUFe8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17:41 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)) 15:17:41 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)) 15:17:41 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)) 15:17:41 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)) 15:17:41 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)) 15:17:41 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)) 15:17:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:17:41 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 15:17:41 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 15:17:41 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 15:17:41 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 15:17:41 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 15:17:41 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 15:17:43 INFO - Downloading blessings-1.6.tar.gz 15:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:17:43 INFO - Installing collected packages: blessings 15:17:43 INFO - Running setup.py install for blessings 15:17:43 INFO - Successfully installed blessings 15:17:43 INFO - Cleaning up... 15:17:44 INFO - Return code: 0 15:17:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:44 INFO - Reading from file tmpfile_stdout 15:17:44 INFO - Current package versions: 15:17:44 INFO - blessings == 1.6 15:17:44 INFO - blobuploader == 1.2.4 15:17:44 INFO - docopt == 0.6.1 15:17:44 INFO - functools32 == 3.2.3-2 15:17:44 INFO - jsonschema == 2.5.1 15:17:44 INFO - manifestparser == 1.1 15:17:44 INFO - mozInstall == 1.12 15:17:44 INFO - mozcrash == 1.0 15:17:44 INFO - mozdebug == 0.1 15:17:44 INFO - mozdevice == 0.49 15:17:44 INFO - mozfile == 1.2 15:17:44 INFO - mozhttpd == 0.7 15:17:44 INFO - mozinfo == 0.9 15:17:44 INFO - mozleak == 0.1 15:17:44 INFO - mozlog == 3.4 15:17:44 INFO - moznetwork == 0.27 15:17:44 INFO - mozprocess == 0.23 15:17:44 INFO - mozprofile == 0.28 15:17:44 INFO - mozrunner == 6.13 15:17:44 INFO - mozscreenshot == 0.1 15:17:44 INFO - mozsystemmonitor == 0.3 15:17:44 INFO - moztest == 0.7 15:17:44 INFO - mozversion == 1.4 15:17:44 INFO - psutil == 3.1.1 15:17:44 INFO - requests == 1.2.3 15:17:44 INFO - wsgiref == 0.1.2 15:17:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:44 INFO - 'HOME': '/Users/cltbld', 15:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:44 INFO - 'LOGNAME': 'cltbld', 15:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:44 INFO - 'MOZ_NO_REMOTE': '1', 15:17:44 INFO - 'NO_EM_RESTART': '1', 15:17:44 INFO - 'PAGER': '/bin/cat', 15:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:44 INFO - 'PWD': '/builds/slave/test', 15:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:44 INFO - 'SHELL': '/bin/bash', 15:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:44 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:44 INFO - 'USER': 'cltbld', 15:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:44 INFO - 'XPC_FLAGS': '0x0', 15:17:44 INFO - 'XPC_SERVICE_NAME': '0', 15:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 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)) 15:17:44 INFO - Cleaning up... 15:17:44 INFO - Return code: 0 15:17:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:44 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 0x10b0e73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b05d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4b3dd7880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, '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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:17:44 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 15:17:44 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 15:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:44 INFO - 'HOME': '/Users/cltbld', 15:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:44 INFO - 'LOGNAME': 'cltbld', 15:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:44 INFO - 'MOZ_NO_REMOTE': '1', 15:17:44 INFO - 'NO_EM_RESTART': '1', 15:17:44 INFO - 'PAGER': '/bin/cat', 15:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:44 INFO - 'PWD': '/builds/slave/test', 15:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:44 INFO - 'SHELL': '/bin/bash', 15:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:17:44 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:44 INFO - 'USER': 'cltbld', 15:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:44 INFO - 'XPC_FLAGS': '0x0', 15:17:44 INFO - 'XPC_SERVICE_NAME': '0', 15:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:17:44 INFO - Cleaning up... 15:17:44 INFO - Return code: 0 15:17:44 INFO - Running post-action listener: _resource_record_post_action 15:17:44 INFO - Running post-action listener: _start_resource_monitoring 15:17:44 INFO - Starting resource monitoring. 15:17:44 INFO - [mozharness: 2016-12-03 23:17:44.867387Z] Finished create-virtualenv step (success) 15:17:44 INFO - [mozharness: 2016-12-03 23:17:44.868269Z] Running install step. 15:17:44 INFO - Running pre-action listener: _resource_record_pre_action 15:17:44 INFO - Running main action method: install 15:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:45 INFO - Reading from file tmpfile_stdout 15:17:45 INFO - Detecting whether we're running mozinstall >=1.0... 15:17:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:17:45 INFO - Reading from file tmpfile_stdout 15:17:45 INFO - Output received: 15:17:45 INFO - Usage: mozinstall [options] installer 15:17:45 INFO - Options: 15:17:45 INFO - -h, --help show this help message and exit 15:17:45 INFO - -d DEST, --destination=DEST 15:17:45 INFO - Directory to install application into. [default: 15:17:45 INFO - "/builds/slave/test"] 15:17:45 INFO - --app=APP Application being installed. [default: firefox] 15:17:45 INFO - mkdir: /builds/slave/test/build/application 15:17:45 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'] 15:17:45 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 15:18:17 INFO - Reading from file tmpfile_stdout 15:18:17 INFO - Output received: 15:18:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:18:17 INFO - Running post-action listener: _resource_record_post_action 15:18:17 INFO - [mozharness: 2016-12-03 23:18:17.635040Z] Finished install step (success) 15:18:17 INFO - [mozharness: 2016-12-03 23:18:17.635196Z] Running run-tests step. 15:18:17 INFO - Running pre-action listener: _resource_record_pre_action 15:18:17 INFO - Running main action method: run_tests 15:18:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:18:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:18:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:18:17 INFO - 2016-12-03 15:18:17.649 screenresolution[1652:8325] starting screenresolution argv=screenresolution get 15:18:17 INFO - 2016-12-03 15:18:17.652 screenresolution[1652:8325] Display 0: 1600x1200x32@60 15:18:17 INFO - 2016-12-03 15:18:17.658 screenresolution[1653:8327] starting screenresolution argv=screenresolution list 15:18:17 INFO - Available Modes on Display 0 15:18:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:18:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:18:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:18:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:18:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:18:17 INFO - Intel Iris: 15:18:17 INFO - Chipset Model: Intel Iris 15:18:17 INFO - Type: GPU 15:18:17 INFO - Bus: Built-In 15:18:17 INFO - VRAM (Dynamic, Max): 1536 MB 15:18:17 INFO - Vendor: Intel (0x8086) 15:18:17 INFO - Device ID: 0x0a2e 15:18:17 INFO - Revision ID: 0x0009 15:18:17 INFO - Displays: 15:18:17 INFO - Display: 15:18:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:18:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:18:17 INFO - Display Serial Number: 1600x1200 60 15:18:17 INFO - Main Display: Yes 15:18:17 INFO - Mirror: Off 15:18:17 INFO - Online: Yes 15:18:17 INFO - Rotation: Supported 15:18:17 INFO - Return code: 0 15:18:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:18:17 INFO - Python 2.7.3 15:18:17 INFO - Return code: 0 15:18:17 INFO - grabbing minidump binary from tooltool 15:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:17 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 0x10aefcd30>, '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 0x10ad63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aef4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:18:17 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 15:18:17 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 15:18:17 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 15:18:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:18:18 INFO - Return code: 0 15:18:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:18:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:18:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:18:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:18:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:18:18 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/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 15:18:18 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/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 15:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hwyg9S44Ky/Render', 15:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:18:18 INFO - 'HOME': '/Users/cltbld', 15:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:18:18 INFO - 'LOGNAME': 'cltbld', 15:18:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:18:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:18:18 INFO - 'MOZ_NO_REMOTE': '1', 15:18:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:18:18 INFO - 'NO_EM_RESTART': '1', 15:18:18 INFO - 'PAGER': '/bin/cat', 15:18:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:18:18 INFO - 'PWD': '/builds/slave/test', 15:18:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:18:18 INFO - 'SHELL': '/bin/bash', 15:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkyQcsHXzd/Listeners', 15:18:18 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:18:18 INFO - 'USER': 'cltbld', 15:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:18:18 INFO - 'XPC_FLAGS': '0x0', 15:18:18 INFO - 'XPC_SERVICE_NAME': '0', 15:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:18:18 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/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 15:18:18 INFO - mozversion application_buildid: 20161203133838 15:18:18 INFO - mozversion application_changeset: 11d06cafe634dfa7981cfdca62c0b34d04a22a84 15:18:18 INFO - mozversion application_display_name: Nightly 15:18:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:18:18 INFO - mozversion application_name: Firefox 15:18:18 INFO - mozversion application_remotingname: firefox 15:18:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:18:18 INFO - mozversion application_vendor: Mozilla 15:18:18 INFO - mozversion application_version: 53.0a1 15:18:18 INFO - mozversion platform_buildid: 20161203133838 15:18:18 INFO - mozversion platform_changeset: 11d06cafe634dfa7981cfdca62c0b34d04a22a84 15:18:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:18:18 INFO - mozversion platform_version: 53.0a1 15:18:18 INFO - using testdate: 1480807098 15:18:18 INFO - actual date: 1480807098 15:18:18 INFO - starting webserver on 'localhost:49242' 15:18:18 INFO - SUITE-START | Running 2 tests 15:18:18 INFO - TEST-START | dromaeo_css 15:18:18 INFO - operating with platform_type : mac_ 15:18:18 INFO - Initialising browser for dromaeo_css test... 15:18:18 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpBGEXx3/profile http://localhost:49242/getInfo.html) 15:18:21 INFO - PROCESS | 1663 | 2016-12-03 15:18:21.601 plugin-container[1665:8507] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e47, name = 'com.apple.tsm.portname' 15:18:21 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:21 INFO - PROCESS | 1663 | 2016-12-03 15:18:21.604 plugin-container[1665:8507] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 15:18:21 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:21 INFO - PROCESS | 1663 | 2016-12-03 15:18:21.604 plugin-container[1665:8507] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:18:21 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:18:21 INFO - PROCESS | 1663 | colorDepth:24 15:18:21 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:18:21 INFO - PROCESS | 1663 | __metrics 15:18:21 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 15:18:22 INFO - TEST-INFO | 1663: exit 0 15:18:22 INFO - Browser initialized. 15:18:22 INFO - Running cycle 1/1 for dromaeo_css test... 15:18:22 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpBGEXx3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:18:25 INFO - PROCESS | 1666 | 2016-12-03 15:18:25.241 plugin-container[1667:8628] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 15:18:25 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:25 INFO - PROCESS | 1666 | 2016-12-03 15:18:25.242 plugin-container[1667:8628] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 15:18:25 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:25 INFO - PROCESS | 1666 | 2016-12-03 15:18:25.242 plugin-container[1667:8628] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:18:34 INFO - PROCESS | 1666 | RSS: Main: 241577984 15:18:34 INFO - PROCESS | 1666 | 15:19:24 INFO - PROCESS | 1666 | 1480807164479 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:21:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 15:21:40 INFO - PROCESS | 1666 | RSS: Main: 251412480 15:21:40 INFO - PROCESS | 1666 | 15:24:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 15:24:43 INFO - PROCESS | 1666 | RSS: Main: 262647808 15:24:43 INFO - PROCESS | 1666 | 15:27:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 15:27:45 INFO - PROCESS | 1666 | RSS: Main: 268115968 15:27:45 INFO - PROCESS | 1666 | 15:30:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 15:30:48 INFO - PROCESS | 1666 | RSS: Main: 266674176 15:30:48 INFO - PROCESS | 1666 | 15:33:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 15:33:52 INFO - PROCESS | 1666 | RSS: Main: 271122432 15:33:52 INFO - PROCESS | 1666 | 15:36:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 15:36:55 INFO - PROCESS | 1666 | RSS: Main: 273956864 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | __start_tp_report 15:36:55 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 15:36:55 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 15:36:55 INFO - PROCESS | 1666 | |i|pagename|runs| 15:36:55 INFO - PROCESS | 1666 | |0;dojo.html;3343.9498407523874;3368.696817286444;3374.6119196292434;3390.779599326044;3404.421248387773;5643.294588176478;5678.829635110943;5689.146628005801;5692.857678558038;5798.884022319551;5708.657480551159;5749.885002299951;5763.962486752383;5784.681842498671;5814.040683287263;4431.601155895969;5115.667481613702;5220.347456567929;5238.580913526908;5325.707086110262;4912.164931961575;5221.791128354861;5256.421793602701;5276;5300.496452836975;4753.3582966299455;5072.365954255718;5246.8688282792855;5251.76367063483;5357.089294819871;4544.636429085665;4986.90026199474;5151.871203219912;5164.6384753067305;5273.920891186597;5145.253938178943;5155.226715992593;5168.043911876315;5183.144781111112;5190.117679994372;5586.13414920688;5735.483806457397;5754.942450575524;5757.539396848242;5787.218725472049;5349.491798279158;5968.432998865101;6100.267967843843;6122.081687746873;6131.110988906628;6177.413145751147;6230.626162430269;6258.843528911768;6262.96661050926;6284.654344011077;5809.738561764731;5844.240248767718;5850.707464626752;5868.706564671749;5869.442402971711;6209.627422354673;6228.28374736902;6256;6271.122042913996;6293.527985401113;6085.57400981927;6117.969410153009;6138.877222455615;6186.536009799283;6198.659073750987;6137.662428566382;6256.749730010749;6260.46786023184;6270.216222972129;6272.686365681698;6112.047575716847;6122.571420000557;6141.946315431794;6196;6199.783007594712;4668.603168730628;5159.019786240602;6206.348333425016;6257.874842503215;6282.842928926722;4913.901721965541;4925.532074452993;4927.531884470969;4927.753612319441;4952.306677065214;3664.4136938089778;4253.191893540217;4433.645308375322;4447.955520444819;4487.035287413222;5440.347158341025;5642.322921249476;5668.546516278688;5710.971445142831;5815.476607105381;6153.923063463876;6162.661053642003;6176.289726681489;6183.690815459299;6234.220722409699;5773.769049238067;5787;5799;5832.708364581754;5856.94143058564;5379.462053794589;5380.3274590676165;5387.191921211849;5401.540869026098;5417.810376636878;6182.536309776695;6201.875962480725;6240.7503699851495;6267.9373206267355;6296.150019747459;6229.719662615284;6254.781082662085;6259.7496100157305;6298;6303.023031430135;6239.344868788712;6289.842753931189;6303.275123360872;6317.494600432046;6334.461570766536;6048.879022419439;6055.7577696893395;6067.514598832171;6135.570510064431;6208;5640.379558248507;5861.6189947653265;6139.43080903429;6157.4458298753325;6176.7838125665385;6078.90881636767;6109.465396926316;6119.654885717031;6128.655903792346;6133.334663918993;6088.360722124113;6092.939070609237;6137.7851775187655;6141.293751218667;6163.3836616338;5843.1235314703135;5856.882862342644;5898.262717160355;6012.128241404885;6047.48596369296;6046.606970547076;6054.152418661303;6078.209832721718;6084.239470066242;6103.298120716175;5862.560307976833;5866.178734977021;5899.67551784656;5899.852503687443;5922.022866227025 15:36:55 INFO - PROCESS | 1666 | |1;ext.html;461.6353081065956;483.5044079818187;523.0167285503254;555.9861003474915;565.7765182752813;28286.575701364494;31325.843370783143;37953.43069853951;37963.43054854173;38003.61996380035;38223;38658.033549161286;38661.613383866155;38682.03294917622;38863.22273554527;8305.750827475185;8525;9100.590473428694;9323.766905827375;10858.891411085906;10855.945720271387;11191.944040279828;11206.887931120707;11219.38293393863;11224.102071834275;11267.943660281688;11272.718182045432;11276.605318813843;11284.774304513905;11290.604828830992;11196.944015279912;11203.495842687018;11244.38155901421;11248.662540123809;11253.606123785628;11075.556977720882;11106.278091924;11122.944385278102;11194.776104477945;11198.776024479464;31068.291243981574;37745.62254377476;37755.81122094399;38102;38156;3132.545780861762;3212.14878057315;3323.7341012718925;3448.9827550862337;3553.3959226931224;28383;28700.13899583016;29462.85268573665;29591.11226663182;29660;3153.180173154973;3162.430762462756;3193.4890417533084;3223.339215460825;3295.044437113258;256979;346557;347794.22057794273;349059;350511.2474437637;55056.898862023336;55468.72265638646;55535.44464555303;55542.72228638831;55605.72197138988;56753;57247;57370;57631.423685763446;57681;14670.413183472541;15164.241787910782;15268.465603703817;15272.465463708939;15273.847261527464;9991.600335986624;10709.518071686793;10972.451377431096;11555.88444115548;11818.468168932419;14898.553043408716;14954.40182392692;14970.251487425585;14981.052463179716;15118.773218401733;5869.706514674249;5963.850903727442;5987.251593550806;5990.101484777231;6009.429104235091;796.3589310604902;798.7858455148142;912.4434095201922;992.0753737748744;1036.6786296248188;28382.71617283842;28720.712792871804;28768.280792980342;29459.85270073679;29805.552916706267;988.649029594498;990.8117457683161;996.7757254617798;1021.6271061062807;1038.7610849504506;13561.796573051412;13957.88336933043;14768.778468322984;14808.629784255265;14965.46773275239;625353;627839;628474.0034098875;629025;630243;45291.54708452939;47543.81140471447;53776;53881.92236155255;53923.46076539263;1303.159462146929;1316.605018494448;1346.7373862096993;1348.4606157537064;1349.865013498662;28561.714382855906;28598.85700571484;28642;29236.853815730785;30000;37865.62134378621;46038.539614603425;46388.768056159504;46403.53596463992;46607.76696116634;477567;566009;571490;574856;576199;24548.877255614323;25251.242462726146;25349.366265843502;26047.348816279744;37198.69804556828;42893.57106428896;43171;43662;43946.12107757891;44701.55298446974;36610.718624848;36739;36826;37899.052523687125;38378;36226.4566031515;36416;37498.8125059373;37555.436668449445;37753.81123094367;34691.8275453652;35471.467927980586;35752.106197345274;35894.64105358913;36017.45973810447;31459;32667.34665306728;32846.1788455281;32976;33078;23702.762972370747;24184.51630967406;24403.877980609985;24726.629100563146;24741.381465463506 15:36:55 INFO - PROCESS | 1666 | |2;jquery.html;363.7017645530666;377.5280898876405;379.77782996946786;381.8167279705741;396.57368329046267;16114.59713507163;18282.634347313047;19514.31699890504;19735.703964440527;20233.898830505863;19300.324488642902;19545.315913943014;19739;19783.307584234517;19788.208471661117;4637.559431853969;5474.890502189954;5641.850383082439;5796.942030579682;5803.883922321551;5610.719464026803;5794.797182098628;5796.159556864252;5800.245968024172;5806.245188125559;5481.095619222823;5673.716314184274;5732.312122545301;5739.62692424991;5756.769729210827;5631.971840140794;5686.232358631614;5700.344460387026;5707.486326230638;5722.084466485342;4738.905221895578;4921.532454416824;4990.750462476862;5005.899882002376;5014.598832093424;24608.876955615287;24615.507689846283;24622.50754984908;24673.506529869483;24962.625560616605;1958.4222654317011;2539.3778524261675;2698.055680511825;2915.2566095645475;2985.7606794903822;8684.088170742107;9981.550830212585;9996.300258981873;10005.699829005142;10398.376097434179;1811.4565630310985;1846.5752876838403;1899.3827006223032;1920.0879582198484;1966.5771859050374;112752.99999999919;117536;121113;121734;122218;9513.762155946157;11154.776904461865;11251.943740281245;11577.089780186176;11593.6521904343;14277.571672849832;14466;14474.421023158955;14488.710225795425;14489.710205795825;6896.551724138016;7989.3209077229085;8249.29880960125;8280.461769984931;8408.537530435762;8115;8129.4715843469985;8192.467489613156;8268.917310826815;8284.792880178044;4521.977390113028;4526.909461810746;4535.16099521583;4548.795304211319;4594.241950078266;1754.7979633950529;1902.1440351841727;1973.8914359710066;1977.7725561560605;1977.9307724229584;372.45993309701043;374.8388193077003;709.2234003765868;752.8456666383378;967.9943774579968;11364.943175284236;11659.533618655329;11791.35147566892;11972.64082077539;11990.280583164864;421.27330355137144;436.92572262714776;456.09010024999674;827.6399766101765;907.5388624314847;10397.53211105487;10703.357798532114;10719;10768.946155269174;10821.621243256606;577301;580328;586377;595543;596280;123081;128505;137107.999999998;138178.5455363624;142240;937.1144268666045;960.3661583355092;1002.3634991780272;1002.3985608634906;1005.1707341443192;14188.716225675222;17895.910520447836;18214.81785182184;18756.718649219998;18762.155702993125;35864.28271434609;38608.42087368636;40617.390739139526;53396;55833;55858;81286.59356703178;81762;81800.09398402167;83757;6639.535232533676;7810.765677029698;7988.281054705104;8037.959810200911;8259.917400825914;12085.093617978511;12282.078844086907;12449.875501244871;12456.688582785502;12589.496420143092;8546.572671366283;8736.91263087361;10483.895161048293;10544.998225168596;10961.397123158304;10777.838332425177;10987.780244395228;11254;11319.037881780141;11331.32012079278;11080.168987326144;11460.885391146316;11489.138314626269;11527.942360288145;11646.94176529112;7999.240072193133;8099.433039687401;8158.551279679675;8417.70538031166;8424.725324162318;10410.323328983592;11149.498272577915;11363.318200907972;11419.543218271176;11486.908743669339 15:36:55 INFO - PROCESS | 1666 | |3;mootools.html;1293.2757655712799;1453.9054961427507;1588.9602759931008;1766.0728117738195;1769.0889192066088;7502.17476077631;7711.845763084735;7937.245961633643;8338.666453341873;8363.954505686788;7843.411744119199;8091.433599648027;8126.593670316475;8228.136045715204;8249;2243.6522339037433;2286.7141607299086;2288.7596802335765;2293.54129174165;2294.208498068164;2269.387265438331;2292.816574674022;2294.070901284974;2316.2124877541632;2319.9072037118494;1878.7369768232459;2248.4378905273684;2255.109231853417;2275.9089636414524;2282.8059614932668;2212.9114835406644;2225.2545397291765;2226.1206823304706;2243.809276211524;2255.424866659008;2257.796798288162;2283.4976305212826;2285.0517035430275;2290.1640901071155;2296.4144143243534;8121.878171827428;8243.917560824375;8253.546054966975;8265.710700125466;8279.337652987746;4211.7051806373565;4760.952390476085;4767.903382222108;5165.819196328148;5169.56058735008;3186.49016157416;3253.1704415374165;3450.9482357764655;3451.2752322012407;3458.757886947915;1031.4584842957433;1058.1957712138753;1065.6430095917876;1082.226208261097;1091.645215305021;10670.679879603624;10678.78642427155;10733.51699173539;10761.192910531741;10809.189310801721;4353.976815448367;4545.954540454619;4591.95408045922;4655.604273636711;4663.347131401607;9165;9318.254539636948;9331.626734930662;9332.206762425134;9335.393199442151;5019.7239151847;5059.443461219336;5075.086484432837;5081.23781432788;5086.669366491166;4234.745915245096;4499.932501012488;4630.930536041962;4652.488226295105;4704.082703872712;3755.455458958436;3760.52993375828;3867.400552914303;3886.3393223152134;3902.239063382613;1223.3332833605707;1240.9131360804656;1244.2596654990266;1249.3877999780225;1252.8496580410408;757.4319260554585;779.8791187365968;809.3929552835374;895.3687650206589;896.2381975320925;5346.385165705916;5443.5100840923715;5569.498745112878;5577.191307260425;5721;693.3309356470929;737.6464188214579;786.9921300787033;806.6612022950375;817.6034623207739;3548.520949671761;3645.2527231917393;3739.8317075731657;3808.7143464240294;3812.6377994090517;8986.326025548111;9381.343305968654;9717.9028209717;9994.800103998024;10186.796264074825;9412.529373531295;9633;9940.900590994142;9942.254330925209;10118.342307749972;7465.141508726458;7674.462787604814;7695.422843286776;7701.653425595861;7710.151883292833;4869.561739443467;5666.433356664301;5671.404502527175;5722.856928576819;5856.912146317723;9007.144321289466;9145.588448519966;9156.771080723036;9160.358774885693;9212.170904618617;9133.132352426508;9238.538073096184;9247.630094796134;9322.673706420243;9431.104045115702;7089.291070892869;7215.494915355875;7516.436267279866;7711.498752581064;7722.922770772445;6816.693248803915;7287.125544934642;7919.326857217201;7988.440809143537;7992.760217193493;6963.1296087989;7006.334398232159;7049.365557099885;7071.858562828817;7072.611006394698;6616.8345791355605;6801.659917004229;6847.554908930903;6872.725090996504;6919.273476284918;6406.743730250738;6556.63938483388;6640.203175618956;6691.5315927884585;6696.83257918556;4294.227039132986;4604.1252162089095;4607.861764147081;4629;4646.790894409692;4596.4714057883775;4811.302361157683;4823.252395878644;4847.345608342828;4972.950270497248 15:36:55 INFO - PROCESS | 1666 | |4;prototype.html;299.6000339546705;306.38569667816034;307.7399597340247;312.17429897919993;313.9983452785614;5177.741112944356;5267.86830329242;5358.383785864627;5387.5151236388765;5490.337186464567;4783.067301876135;4995.277573722321;5081.6696914700515;5169.250458683488;5377.5966802489775;4122.42286079949;4132.111596006858;4150.771707556083;4154.376843473488;4169.041120542277;3700.352438323296;3916.373380259159;4155.97922010391;4189.937150942738;4209.98960249541;4054.513458384998;4119.423280740699;4139.81370838313;4139.958600413987;4158.64651504621;3809.8857034289017;3926.8219534139785;4122.505299364066;4141.585841415865;4149.460570125894;3638.072291565634;3642.289753498069;3652.1600031992525;3667.8166091695307;3692.5568931728367;5221.817236396746;5264.920691258283;5268.841934741964;5306.469353064701;5349.090654588729;4172.4784401949755;4395.274779661352;4739.241721324606;4753.3107699383745;4776.832810851603;5372.54333381663;5672.68800215988;5708.94477987924;5758.3665796762325;5786.276715410574;3770.3213421584105;3978.4629075074786;3981.9203615927518;3988.840446382141;4029.8388064477385;4992.475790042066;5965.761369545213;5967.8209653710455;5985.8802823943715;6016.368281330485;3277.8197199154274;3468.5490886184634;4054.655354294918;4057.776822274803;4086.734362266443;3146.3167205544923;3367.5958884933966;3878.108035151931;4114.382842573578;4130.504339479282;4725.00774837288;4730.408698912636;4747.216709243005;4757.9065211306915;4769.880752981134;4978.850634480971;5651.9152212716845;5653.971730141323;5702.889930725658;5803.622764520293;997.486294558303;1245.701031752373;1276.7829468990294;1296.7989961555975;1310.8361454818148;787.5983248543284;890.3011136258007;924.4360939826701;925.9305502145033;930.4045410937006;4608.769561521911;5073.644844860898;5085.618578606545;5090.77091530882;5100.948990510047;4962.15643340633;4999.375078115236;5373.7850485980125;5498.862528436741;5576.553875690016;4573.451185857718;4682.648801339913;4959.428756897054;5036.521530454601;5047.520485553867;2657.8803953822126;2759.696433392325;2922.261254553537;2940.412177424167;3020.4412183746076;5014.598832093424;5789.45000224978;5798.797042103506;5802.390748971382;5883.205767221456;4677.573340131293;5177.197534382177;5737.88524229513;6000.679510752251;6031.517478601789;5120.539151476385;5995.970020149958;6091.299500557493;6101.877962440726;6115.755369785248;5615.747291371979;5841.50347220491;5895.997680394429;5908.257064165995;5912.320083190316;5811.53507719373;5840.649561026182;5868.627872235212;5872.265966754156;5875.738964874536;5794.420557944172;6099.4510494053975;6118.326984031664;6151.6924153793025;6158.397337557579;3724.348239058208;3772.8490860365278;3872.0900588362274;3914.784686842137;3919.0790164310793;5185.80726432915;5303.867403314948;5913.852153696192;5929.318128415288;5943.494802941798;5124.538791508782;5272.86817829542;5662.490375866192;5775.624584402;5869.148973398918;4528.3660287559105;4921.941782516776;5803.7388317524965;5812;5828.795992140254;5120.283160357628;5365.785368585369;5637.46444087811;5693.231413759255;5700.515456186271;5747.339056008613;5766.163906233656;5822.184894114743;5835.387284335254;5835.416458354131;4997.575206107376;5661.830145095653;5678.403767604342;5680.4319568042865;5696.259486266758 15:36:55 INFO - PROCESS | 1666 | |5;yui.html;669.9095622091016;686.2005763285772;706.3995603736824;742.3764038207904;792.6643605524689;13958.371873265702;14816.259187040632;14908.478203262888;14935;14975.999999999987;13353.866461335383;13810.516631917884;13947.651308717288;14098.365573549188;14100.717985640282;817.0930267403178;830.2859540794911;830.5307501261768;837.1377481194379;839.4879123734516;647.4917190005847;667.5594107888794;808.915063918289;822.4284122534841;825.5170725125809;861.9741407757778;879.3361012435602;880.6961598248594;881.9470831750116;883.196291374849;828.7223780033696;857.9442336248123;862.3489265604492;865.4201684871151;871.2681347667989;548.5995223486877;631.8072987738738;641.9775307864203;643.4144928115393;644.5520363347475;12349.444275007647;12692.30192339421;14567.562973110824;15180;15261.313240904186;14058.734713875698;14162;14211.21838298893;14320.856791432056;14362.281885905768;10107.63546921171;11801.993661077551;11901.226420282654;12023.977961873252;12336.938315308456;3618.6924111450558;3814.7138964577766;3820.8853734388013;3844.0774214188673;3859.36320507116;119043.4047829764;123804.38097809543;125926;126264;126290;8798.29613630908;9862.802743945082;10836.512356943957;10984.835227471594;11083.057940075183;22147.446313842283;22229.333120006424;22263.554728905332;22269.443263918532;22320.21879234219;7532.17146113927;7625.084989801259;7649.31156195945;7771;7855.5286682799215;4154.086101057763;4247.277962746341;4252.85115020979;4262.147570485915;4275.8075886585175;3397.8810741623925;3478.89563313101;3505.4216054350745;3506.7369947253546;3513.262214934892;551.0796969061677;598.9219405070855;608.4219991008471;618.2611778924143;621.7109044294425;584.6024703201779;605.833395816154;626.7493002798917;651.5504302031583;670.0786418674322;8700.608472618747;8855.73432797017;8877.467351958903;9152.947411047682;9157.542122893828;622.2129006806393;643.5109316919176;648.9286178520359;653.7319698923513;667.7362441835447;4282.250606143913;4314.80583373749;4441.601535170971;4545.001097344434;4621.23749581322;108463.45768271109;112036;113034.43482782533;115431;115921.42039289749;83930.5803470991;90481.54759226162;91647.54176229076;100313;106641;82280.58859705663;85184.57407712922;85335;85620.57189714136;85823;7618.733344332922;8008.879866802119;8520.659173632985;8655.48067115975;8750.212480876751;43183.568164317956;53177.7341113292;55599.61000975008;56589.47284217335;56693.71653141708;72405.27594724196;77205.61397193203;77868.22131778837;79301.81047284411;79412.80880786908;11027.72430689207;11198.664040078811;12627.684307892008;12724.936375318064;13473.865261347653;12084.982150570693;12480.687982800502;13899.513517026855;13915.652108697364;14644.633884152981;12955.352232388155;12981.221126732426;13050.477980881038;13252.271125088213;13533.526326578523;13088;13190.188608203045;13204.27376494302;13465.528706495226;13686.863131368558;9884.604615815575;12396.876031239934;12963.546275880299;13021;13387.196768193939;10609.160869174693;11022.779544408906;11281.379524125923;11295.491702873225;11408.372539510076;8872.689455869013;9779.728635277368;11009.504572294427;11096.420206101766;11278.718032049264 15:36:55 INFO - PROCESS | 1666 | __end_tp_report 15:36:55 INFO - PROCESS | 1666 | __start_cc_report 15:36:55 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,378 15:36:55 INFO - PROCESS | 1666 | __end_cc_report 15:36:55 INFO - PROCESS | 1666 | __startTimestamp1480808215083__endTimestamp 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | ------- Summary: start ------- 15:36:55 INFO - PROCESS | 1666 | Number of tests: 6 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5695.44 Median:5868.71 stddev:659.70 (11.2%) stddev-sans-first:635.41 15:36:55 INFO - PROCESS | 1666 | Values: 3343.9 3368.7 3374.6 3390.8 3404.4 5643.3 5678.8 5689.1 5692.9 5798.9 5708.7 5749.9 5764.0 5784.7 5814.0 4431.6 5115.7 5220.3 5238.6 5325.7 4912.2 5221.8 5256.4 5276.0 5300.5 4753.4 5072.4 5246.9 5251.8 5357.1 4544.6 4986.9 5151.9 5164.6 5273.9 5145.3 5155.2 5168.0 5183.1 5190.1 5586.1 5735.5 5754.9 5757.5 5787.2 5349.5 5968.4 6100.3 6122.1 6131.1 6177.4 6230.6 6258.8 6263.0 6284.7 5809.7 5844.2 5850.7 5868.7 5869.4 6209.6 6228.3 6256.0 6271.1 6293.5 6085.6 6118.0 6138.9 6186.5 6198.7 6137.7 6256.7 6260.5 6270.2 6272.7 6112.0 6122.6 6141.9 6196.0 6199.8 4668.6 5159.0 6206.3 6257.9 6282.8 4913.9 4925.5 4927.5 4927.8 4952.3 3664.4 4253.2 4433.6 4448.0 4487.0 5440.3 5642.3 5668.5 5711.0 5815.5 6153.9 6162.7 6176.3 6183.7 6234.2 5773.8 5787.0 5799.0 5832.7 5856.9 5379.5 5380.3 5387.2 5401.5 5417.8 6182.5 6201.9 6240.8 6267.9 6296.2 6229.7 6254.8 6259.7 6298.0 6303.0 6239.3 6289.8 6303.3 6317.5 6334.5 6048.9 6055.8 6067.5 6135.6 6208.0 5640.4 5861.6 6139.4 6157.4 6176.8 6078.9 6109.5 6119.7 6128.7 6133.3 6088.4 6092.9 6137.8 6141.3 6163.4 5843.1 5856.9 5898.3 6012.1 6047.5 6046.6 6054.2 6078.2 6084.2 6103.3 5862.6 5866.2 5899.7 5899.9 5922.0 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:63363.09 Median:190624.41 stddev:139364.74 (73.1%) stddev-sans-first:139675.67 15:36:55 INFO - PROCESS | 1666 | Values: 461.6 483.5 523.0 556.0 565.8 28286.6 31325.8 37953.4 37963.4 38003.6 38223.0 38658.0 38661.6 38682.0 38863.2 8305.8 8525.0 9100.6 9323.8 10858.9 10855.9 11191.9 11206.9 11219.4 11224.1 11267.9 11272.7 11276.6 11284.8 11290.6 11196.9 11203.5 11244.4 11248.7 11253.6 11075.6 11106.3 11122.9 11194.8 11198.8 31068.3 37745.6 37755.8 38102.0 38156.0 3132.5 3212.1 3323.7 3449.0 3553.4 28383.0 28700.1 29462.9 29591.1 29660.0 3153.2 3162.4 3193.5 3223.3 3295.0 256979.0 346557.0 347794.2 349059.0 350511.2 55056.9 55468.7 55535.4 55542.7 55605.7 56753.0 57247.0 57370.0 57631.4 57681.0 14670.4 15164.2 15268.5 15272.5 15273.8 9991.6 10709.5 10972.5 11555.9 11818.5 14898.6 14954.4 14970.3 14981.1 15118.8 5869.7 5963.9 5987.3 5990.1 6009.4 796.4 798.8 912.4 992.1 1036.7 28382.7 28720.7 28768.3 29459.9 29805.6 988.6 990.8 996.8 1021.6 1038.8 13561.8 13957.9 14768.8 14808.6 14965.5 625353.0 627839.0 628474.0 629025.0 630243.0 45291.5 47543.8 53776.0 53881.9 53923.5 1303.2 1316.6 1346.7 1348.5 1349.9 28561.7 28598.9 28642.0 29236.9 30000.0 37865.6 46038.5 46388.8 46403.5 46607.8 477567.0 566009.0 571490.0 574856.0 576199.0 24548.9 25251.2 25349.4 26047.3 37198.7 42893.6 43171.0 43662.0 43946.1 44701.6 36610.7 36739.0 36826.0 37899.1 38378.0 36226.5 36416.0 37498.8 37555.4 37753.8 34691.8 35471.5 35752.1 35894.6 36017.5 31459.0 32667.3 32846.2 32976.0 33078.0 23702.8 24184.5 24403.9 24726.6 24741.4 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34150.04 Median:2950.51 stddev:98382.17 (3334.4%) stddev-sans-first:98625.45 15:36:55 INFO - PROCESS | 1666 | Values: 363.7 377.5 379.8 381.8 396.6 16114.6 18282.6 19514.3 19735.7 20233.9 19300.3 19545.3 19739.0 19783.3 19788.2 4637.6 5474.9 5641.9 5796.9 5803.9 5610.7 5794.8 5796.2 5800.2 5806.2 5481.1 5673.7 5732.3 5739.6 5756.8 5632.0 5686.2 5700.3 5707.5 5722.1 4738.9 4921.5 4990.8 5005.9 5014.6 24608.9 24615.5 24622.5 24673.5 24962.6 1958.4 2539.4 2698.1 2915.3 2985.8 8684.1 9981.6 9996.3 10005.7 10398.4 1811.5 1846.6 1899.4 1920.1 1966.6 112753.0 117536.0 121113.0 121734.0 122218.0 9513.8 11154.8 11251.9 11577.1 11593.7 14277.6 14466.0 14474.4 14488.7 14489.7 6896.6 7989.3 8249.3 8280.5 8408.5 8115.0 8129.5 8192.5 8268.9 8284.8 4522.0 4526.9 4535.2 4548.8 4594.2 1754.8 1902.1 1973.9 1977.8 1977.9 372.5 374.8 709.2 752.8 968.0 11364.9 11659.5 11791.4 11972.6 11990.3 421.3 436.9 456.1 827.6 907.5 10397.5 10703.4 10719.0 10768.9 10821.6 577301.0 580328.0 586377.0 595543.0 596280.0 123081.0 128505.0 137108.0 138178.5 142240.0 937.1 960.4 1002.4 1002.4 1005.2 14188.7 17895.9 18214.8 18756.7 18762.2 35864.3 38608.4 40617.4 53396.0 55833.0 55858.0 81286.6 81762.0 81800.1 83757.0 6639.5 7810.8 7988.3 8038.0 8259.9 12085.1 12282.1 12449.9 12456.7 12589.5 8546.6 8736.9 10483.9 10545.0 10961.4 10777.8 10987.8 11254.0 11319.0 11331.3 11080.2 11460.9 11489.1 11527.9 11646.9 7999.2 8099.4 8158.6 8417.7 8424.7 10410.3 11149.5 11363.3 11419.5 11486.9 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5346.26 Median:5126.24 stddev:2934.43 (57.2%) stddev-sans-first:2926.85 15:36:55 INFO - PROCESS | 1666 | Values: 1293.3 1453.9 1589.0 1766.1 1769.1 7502.2 7711.8 7937.2 8338.7 8364.0 7843.4 8091.4 8126.6 8228.1 8249.0 2243.7 2286.7 2288.8 2293.5 2294.2 2269.4 2292.8 2294.1 2316.2 2319.9 1878.7 2248.4 2255.1 2275.9 2282.8 2212.9 2225.3 2226.1 2243.8 2255.4 2257.8 2283.5 2285.1 2290.2 2296.4 8121.9 8243.9 8253.5 8265.7 8279.3 4211.7 4761.0 4767.9 5165.8 5169.6 3186.5 3253.2 3450.9 3451.3 3458.8 1031.5 1058.2 1065.6 1082.2 1091.6 10670.7 10678.8 10733.5 10761.2 10809.2 4354.0 4546.0 4592.0 4655.6 4663.3 9165.0 9318.3 9331.6 9332.2 9335.4 5019.7 5059.4 5075.1 5081.2 5086.7 4234.7 4499.9 4630.9 4652.5 4704.1 3755.5 3760.5 3867.4 3886.3 3902.2 1223.3 1240.9 1244.3 1249.4 1252.8 757.4 779.9 809.4 895.4 896.2 5346.4 5443.5 5569.5 5577.2 5721.0 693.3 737.6 787.0 806.7 817.6 3548.5 3645.3 3739.8 3808.7 3812.6 8986.3 9381.3 9717.9 9994.8 10186.8 9412.5 9633.0 9940.9 9942.3 10118.3 7465.1 7674.5 7695.4 7701.7 7710.2 4869.6 5666.4 5671.4 5722.9 5856.9 9007.1 9145.6 9156.8 9160.4 9212.2 9133.1 9238.5 9247.6 9322.7 9431.1 7089.3 7215.5 7516.4 7711.5 7722.9 6816.7 7287.1 7919.3 7988.4 7992.8 6963.1 7006.3 7049.4 7071.9 7072.6 6616.8 6801.7 6847.6 6872.7 6919.3 6406.7 6556.6 6640.2 6691.5 6696.8 4294.2 4604.1 4607.9 4629.0 4646.8 4596.5 4811.3 4823.3 4847.3 4973.0 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4610.51 Median:5110.62 stddev:1428.68 (28.0%) stddev-sans-first:1395.56 15:36:55 INFO - PROCESS | 1666 | Values: 299.6 306.4 307.7 312.2 314.0 5177.7 5267.9 5358.4 5387.5 5490.3 4783.1 4995.3 5081.7 5169.3 5377.6 4122.4 4132.1 4150.8 4154.4 4169.0 3700.4 3916.4 4156.0 4189.9 4210.0 4054.5 4119.4 4139.8 4140.0 4158.6 3809.9 3926.8 4122.5 4141.6 4149.5 3638.1 3642.3 3652.2 3667.8 3692.6 5221.8 5264.9 5268.8 5306.5 5349.1 4172.5 4395.3 4739.2 4753.3 4776.8 5372.5 5672.7 5708.9 5758.4 5786.3 3770.3 3978.5 3981.9 3988.8 4029.8 4992.5 5965.8 5967.8 5985.9 6016.4 3277.8 3468.5 4054.7 4057.8 4086.7 3146.3 3367.6 3878.1 4114.4 4130.5 4725.0 4730.4 4747.2 4757.9 4769.9 4978.9 5651.9 5654.0 5702.9 5803.6 997.5 1245.7 1276.8 1296.8 1310.8 787.6 890.3 924.4 925.9 930.4 4608.8 5073.6 5085.6 5090.8 5100.9 4962.2 4999.4 5373.8 5498.9 5576.6 4573.5 4682.6 4959.4 5036.5 5047.5 2657.9 2759.7 2922.3 2940.4 3020.4 5014.6 5789.5 5798.8 5802.4 5883.2 4677.6 5177.2 5737.9 6000.7 6031.5 5120.5 5996.0 6091.3 6101.9 6115.8 5615.7 5841.5 5896.0 5908.3 5912.3 5811.5 5840.6 5868.6 5872.3 5875.7 5794.4 6099.5 6118.3 6151.7 6158.4 3724.3 3772.8 3872.1 3914.8 3919.1 5185.8 5303.9 5913.9 5929.3 5943.5 5124.5 5272.9 5662.5 5775.6 5869.1 4528.4 4921.9 5803.7 5812.0 5828.8 5120.3 5365.8 5637.5 5693.2 5700.5 5747.3 5766.2 5822.2 5835.4 5835.4 4997.6 5661.8 5678.4 5680.4 5696.3 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:21705.06 Median:4268.98 stddev:33081.91 (774.9%) stddev-sans-first:33137.01 15:36:55 INFO - PROCESS | 1666 | Values: 669.9 686.2 706.4 742.4 792.7 13958.4 14816.3 14908.5 14935.0 14976.0 13353.9 13810.5 13947.7 14098.4 14100.7 817.1 830.3 830.5 837.1 839.5 647.5 667.6 808.9 822.4 825.5 862.0 879.3 880.7 881.9 883.2 828.7 857.9 862.3 865.4 871.3 548.6 631.8 642.0 643.4 644.6 12349.4 12692.3 14567.6 15180.0 15261.3 14058.7 14162.0 14211.2 14320.9 14362.3 10107.6 11802.0 11901.2 12024.0 12336.9 3618.7 3814.7 3820.9 3844.1 3859.4 119043.4 123804.4 125926.0 126264.0 126290.0 8798.3 9862.8 10836.5 10984.8 11083.1 22147.4 22229.3 22263.6 22269.4 22320.2 7532.2 7625.1 7649.3 7771.0 7855.5 4154.1 4247.3 4252.9 4262.1 4275.8 3397.9 3478.9 3505.4 3506.7 3513.3 551.1 598.9 608.4 618.3 621.7 584.6 605.8 626.7 651.6 670.1 8700.6 8855.7 8877.5 9152.9 9157.5 622.2 643.5 648.9 653.7 667.7 4282.3 4314.8 4441.6 4545.0 4621.2 108463.5 112036.0 113034.4 115431.0 115921.4 83930.6 90481.5 91647.5 100313.0 106641.0 82280.6 85184.6 85335.0 85620.6 85823.0 7618.7 8008.9 8520.7 8655.5 8750.2 43183.6 53177.7 55599.6 56589.5 56693.7 72405.3 77205.6 77868.2 79301.8 79412.8 11027.7 11198.7 12627.7 12724.9 13473.9 12085.0 12480.7 13899.5 13915.7 14644.6 12955.4 12981.2 13050.5 13252.3 13533.5 13088.0 13190.2 13204.3 13465.5 13686.9 9884.6 12396.9 12963.5 13021.0 13387.2 10609.2 11022.8 11281.4 11295.5 11408.4 8872.7 9779.7 11009.5 11096.4 11278.7 15:36:55 INFO - PROCESS | 1666 | -------- Summary: end -------- 15:36:55 INFO - PROCESS | 1666 | 15:36:55 INFO - TEST-INFO | 1666: exit 0 15:36:55 INFO - TEST-OK | dromaeo_css | took 1117461ms 15:36:55 INFO - TEST-START | kraken 15:36:55 INFO - operating with platform_type : mac_ 15:36:55 INFO - Initialising browser for kraken test... 15:36:55 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpYj4xUe/profile http://localhost:49242/getInfo.html) 15:36:58 INFO - PROCESS | 1675 | 2016-12-03 15:36:58.681 plugin-container[1676:10702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 15:36:58 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:58 INFO - PROCESS | 1675 | 2016-12-03 15:36:58.684 plugin-container[1676:10702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 15:36:58 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:58 INFO - PROCESS | 1675 | 2016-12-03 15:36:58.684 plugin-container[1676:10702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:36:58 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 15:36:58 INFO - PROCESS | 1675 | colorDepth:24 15:36:58 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 15:36:58 INFO - PROCESS | 1675 | __metrics 15:36:58 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 15:36:59 INFO - TEST-INFO | 1675: exit 0 15:36:59 INFO - Browser initialized. 15:36:59 INFO - Running cycle 1/1 for kraken test... 15:36:59 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpYj4xUe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:37:02 INFO - PROCESS | 1677 | 2016-12-03 15:37:02.247 plugin-container[1678:10821] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 15:37:02 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:37:02 INFO - PROCESS | 1677 | 2016-12-03 15:37:02.247 plugin-container[1678:10821] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8917, name = 'com.apple.CFPasteboardClient' 15:37:02 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:37:02 INFO - PROCESS | 1677 | 2016-12-03 15:37:02.247 plugin-container[1678:10821] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:37:11 INFO - PROCESS | 1677 | RSS: Main: 241610752 15:37:11 INFO - PROCESS | 1677 | 15:37:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 15:37:15 INFO - PROCESS | 1677 | RSS: Main: 254431232 15:37:15 INFO - PROCESS | 1677 | 15:37:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 15:37:19 INFO - PROCESS | 1677 | RSS: Main: 261824512 15:37:19 INFO - PROCESS | 1677 | 15:37:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 15:37:23 INFO - PROCESS | 1677 | RSS: Main: 264519680 15:37:23 INFO - PROCESS | 1677 | 15:37:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 15:37:25 INFO - PROCESS | 1677 | RSS: Main: 265695232 15:37:25 INFO - PROCESS | 1677 | 15:37:27 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:37:28 INFO - PROCESS | 1677 | RSS: Main: 267304960 15:37:28 INFO - PROCESS | 1677 | 15:37:31 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 15:37:31 INFO - PROCESS | 1677 | RSS: Main: 272699392 15:37:31 INFO - PROCESS | 1677 | 15:37:35 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 15:37:35 INFO - PROCESS | 1677 | RSS: Main: 272531456 15:37:35 INFO - PROCESS | 1677 | 15:37:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 15:37:39 INFO - PROCESS | 1677 | RSS: Main: 272359424 15:37:39 INFO - PROCESS | 1677 | 15:37:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:37:41 INFO - PROCESS | 1677 | RSS: Main: 271695872 15:37:41 INFO - PROCESS | 1677 | 15:37:43 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:37:44 INFO - PROCESS | 1677 | RSS: Main: 271609856 15:37:44 INFO - PROCESS | 1677 | 15:37:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:37:47 INFO - PROCESS | 1677 | RSS: Main: 271708160 15:37:47 INFO - PROCESS | 1677 | 15:37:50 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:37:51 INFO - PROCESS | 1677 | RSS: Main: 271978496 15:37:51 INFO - PROCESS | 1677 | 15:37:54 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:37:54 INFO - PROCESS | 1677 | RSS: Main: 279879680 15:37:54 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 15:37:56 INFO - PROCESS | 1677 | RSS: Main: 280338432 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | __start_tp_report 15:37:56 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 15:37:56 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 15:37:56 INFO - PROCESS | 1677 | |i|pagename|runs| 15:37:56 INFO - PROCESS | 1677 | |0;ai-astar;107;118;118;117;117;117;118;117;123;120 15:37:56 INFO - PROCESS | 1677 | |1;audio-beat-detection;148;117;117;151;116;120;118;121;139;103 15:37:56 INFO - PROCESS | 1677 | |2;audio-dft;196;205;208;203;197;217;199;271;247;196 15:37:56 INFO - PROCESS | 1677 | |3;audio-fft;82;66;84;99;62;60;65;113;100;63 15:37:56 INFO - PROCESS | 1677 | |4;audio-oscillator;85;71;83;72;71;71;84;77;85;84 15:37:56 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;92;84;89;86;90;85;91;86;89;106 15:37:56 INFO - PROCESS | 1677 | |6;imaging-darkroom;99;100;99;113;99;99;125;175;102;131 15:37:56 INFO - PROCESS | 1677 | |7;imaging-desaturate;94;93;92;92;96;98;144;126;96;120 15:37:56 INFO - PROCESS | 1677 | |8;json-parse-financial;56;56;59;58;59;61;56;58;58;70 15:37:56 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;70;112;107;83;79;73;89;61;72;68 15:37:56 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;102;87;94;105;78;78;100;113;108;82 15:37:56 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;151;158;150;164;166;150;155;158;265;187 15:37:56 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;183;178;178;179;192;175;177;175;161;168 15:37:56 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;68;70;71;67;70;71;72;73;71;71 15:37:56 INFO - PROCESS | 1677 | __end_tp_report 15:37:56 INFO - PROCESS | 1677 | __start_cc_report 15:37:56 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,950 15:37:56 INFO - PROCESS | 1677 | __end_cc_report 15:37:56 INFO - PROCESS | 1677 | __startTimestamp1480808276673__endTimestamp 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | ------- Summary: start ------- 15:37:56 INFO - PROCESS | 1677 | Number of tests: 14 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:117.20 Median:118.00 stddev:4.05 (3.4%) stddev-sans-first:2.00 15:37:56 INFO - PROCESS | 1677 | Values: 107.0 118.0 118.0 117.0 117.0 117.0 118.0 117.0 123.0 120.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:120.50 stddev:15.58 (12.9%) stddev-sans-first:14.13 15:37:56 INFO - PROCESS | 1677 | Values: 148.0 117.0 117.0 151.0 116.0 120.0 118.0 121.0 139.0 103.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:213.90 Median:206.50 stddev:25.27 (12.2%) stddev-sans-first:25.96 15:37:56 INFO - PROCESS | 1677 | Values: 196.0 205.0 208.0 203.0 197.0 217.0 199.0 271.0 247.0 196.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:79.40 Median:65.50 stddev:19.15 (29.2%) stddev-sans-first:20.29 15:37:56 INFO - PROCESS | 1677 | Values: 82.0 66.0 84.0 99.0 62.0 60.0 65.0 113.0 100.0 63.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:83.50 stddev:6.48 (7.8%) stddev-sans-first:6.41 15:37:56 INFO - PROCESS | 1677 | Values: 85.0 71.0 83.0 72.0 71.0 71.0 84.0 77.0 85.0 84.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:89.00 stddev:6.29 (7.1%) stddev-sans-first:6.62 15:37:56 INFO - PROCESS | 1677 | Values: 92.0 84.0 89.0 86.0 90.0 85.0 91.0 86.0 89.0 106.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:114.20 Median:137.00 stddev:24.43 (17.8%) stddev-sans-first:25.29 15:37:56 INFO - PROCESS | 1677 | Values: 99.0 100.0 99.0 113.0 99.0 99.0 125.0 175.0 102.0 131.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:105.10 Median:93.50 stddev:18.26 (19.5%) stddev-sans-first:18.92 15:37:56 INFO - PROCESS | 1677 | Values: 94.0 93.0 92.0 92.0 96.0 98.0 144.0 126.0 96.0 120.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:58.50 stddev:4.15 (7.1%) stddev-sans-first:4.25 15:37:56 INFO - PROCESS | 1677 | Values: 56.0 56.0 59.0 58.0 59.0 61.0 56.0 58.0 58.0 70.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:81.40 Median:72.50 stddev:16.81 (23.2%) stddev-sans-first:17.31 15:37:56 INFO - PROCESS | 1677 | Values: 70.0 112.0 107.0 83.0 79.0 73.0 89.0 61.0 72.0 68.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:94.70 Median:78.00 stddev:12.82 (16.4%) stddev-sans-first:13.32 15:37:56 INFO - PROCESS | 1677 | Values: 102.0 87.0 94.0 105.0 78.0 78.0 100.0 113.0 108.0 82.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:170.40 Median:161.00 stddev:35.02 (21.8%) stddev-sans-first:36.44 15:37:56 INFO - PROCESS | 1677 | Values: 151.0 158.0 150.0 164.0 166.0 150.0 155.0 158.0 265.0 187.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.60 Median:178.00 stddev:8.24 (4.6%) stddev-sans-first:8.40 15:37:56 INFO - PROCESS | 1677 | Values: 183.0 178.0 178.0 179.0 192.0 175.0 177.0 175.0 161.0 168.0 15:37:56 INFO - PROCESS | 1677 | 15:37:56 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:1.78 (2.5%) stddev-sans-first:1.66 15:37:56 INFO - PROCESS | 1677 | Values: 68.0 70.0 71.0 67.0 70.0 71.0 72.0 73.0 71.0 71.0 15:37:56 INFO - PROCESS | 1677 | -------- Summary: end -------- 15:37:56 INFO - PROCESS | 1677 | 15:37:57 INFO - TEST-INFO | 1677: exit 0 15:37:57 INFO - TEST-OK | kraken | took 62041ms 15:37:57 INFO - SUITE-END | took 1179s 15:37:57 INFO - Completed test suite (00:19:39) 15:37:57 INFO - javascript benchmark 15:37:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7089.291637098631, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.9498407523874, 3368.696817286444, 3374.6119196292434, 3390.779599326044, 3404.421248387773, 5643.294588176478, 5678.829635110943, 5689.146628005801, 5692.857678558038, 5798.884022319551, 5708.657480551159, 5749.885002299951, 5763.962486752383, 5784.681842498671, 5814.040683287263, 4431.601155895969, 5115.667481613702, 5220.347456567929, 5238.580913526908, 5325.707086110262, 4912.164931961575, 5221.791128354861, 5256.421793602701, 5276.0, 5300.496452836975, 4753.3582966299455, 5072.365954255718, 5246.8688282792855, 5251.76367063483, 5357.089294819871, 4544.636429085665, 4986.90026199474, 5151.871203219912, 5164.6384753067305, 5273.920891186597, 5145.253938178943, 5155.226715992593, 5168.043911876315, 5183.144781111112, 5190.117679994372, 5586.13414920688, 5735.483806457397, 5754.942450575524, 5757.539396848242, 5787.218725472049, 5349.491798279158, 5968.432998865101, 6100.267967843843, 6122.081687746873, 6131.110988906628, 6177.413145751147, 6230.626162430269, 6258.843528911768, 6262.96661050926, 6284.654344011077, 5809.738561764731, 5844.240248767718, 5850.707464626752, 5868.706564671749, 5869.442402971711, 6209.627422354673, 6228.28374736902, 6256.0, 6271.122042913996, 6293.527985401113, 6085.57400981927, 6117.969410153009, 6138.877222455615, 6186.536009799283, 6198.659073750987, 6137.662428566382, 6256.749730010749, 6260.46786023184, 6270.216222972129, 6272.686365681698, 6112.047575716847, 6122.571420000557, 6141.946315431794, 6196.0, 6199.783007594712, 4668.603168730628, 5159.019786240602, 6206.348333425016, 6257.874842503215, 6282.842928926722, 4913.901721965541, 4925.532074452993, 4927.531884470969, 4927.753612319441, 4952.306677065214, 3664.4136938089778, 4253.191893540217, 4433.645308375322, 4447.955520444819, 4487.035287413222, 5440.347158341025, 5642.322921249476, 5668.546516278688, 5710.971445142831, 5815.476607105381, 6153.923063463876, 6162.661053642003, 6176.289726681489, 6183.690815459299, 6234.220722409699, 5773.769049238067, 5787.0, 5799.0, 5832.708364581754, 5856.94143058564, 5379.462053794589, 5380.3274590676165, 5387.191921211849, 5401.540869026098, 5417.810376636878, 6182.536309776695, 6201.875962480725, 6240.7503699851495, 6267.9373206267355, 6296.150019747459, 6229.719662615284, 6254.781082662085, 6259.7496100157305, 6298.0, 6303.023031430135, 6239.344868788712, 6289.842753931189, 6303.275123360872, 6317.494600432046, 6334.461570766536, 6048.879022419439, 6055.7577696893395, 6067.514598832171, 6135.570510064431, 6208.0, 5640.379558248507, 5861.6189947653265, 6139.43080903429, 6157.4458298753325, 6176.7838125665385, 6078.90881636767, 6109.465396926316, 6119.654885717031, 6128.655903792346, 6133.334663918993, 6088.360722124113, 6092.939070609237, 6137.7851775187655, 6141.293751218667, 6163.3836616338, 5843.1235314703135, 5856.882862342644, 5898.262717160355, 6012.128241404885, 6047.48596369296, 6046.606970547076, 6054.152418661303, 6078.209832721718, 6084.239470066242, 6103.298120716175, 5862.560307976833, 5866.178734977021, 5899.67551784656, 5899.852503687443, 5922.022866227025], "value": 5653.400688100389, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.6353081065956, 483.5044079818187, 523.0167285503254, 555.9861003474915, 565.7765182752813, 28286.575701364494, 31325.843370783143, 37953.43069853951, 37963.43054854173, 38003.61996380035, 38223.0, 38658.033549161286, 38661.613383866155, 38682.03294917622, 38863.22273554527, 8305.750827475185, 8525.0, 9100.590473428694, 9323.766905827375, 10858.891411085906, 10855.945720271387, 11191.944040279828, 11206.887931120707, 11219.38293393863, 11224.102071834275, 11267.943660281688, 11272.718182045432, 11276.605318813843, 11284.774304513905, 11290.604828830992, 11196.944015279912, 11203.495842687018, 11244.38155901421, 11248.662540123809, 11253.606123785628, 11075.556977720882, 11106.278091924, 11122.944385278102, 11194.776104477945, 11198.776024479464, 31068.291243981574, 37745.62254377476, 37755.81122094399, 38102.0, 38156.0, 3132.545780861762, 3212.14878057315, 3323.7341012718925, 3448.9827550862337, 3553.3959226931224, 28383.0, 28700.13899583016, 29462.85268573665, 29591.11226663182, 29660.0, 3153.180173154973, 3162.430762462756, 3193.4890417533084, 3223.339215460825, 3295.044437113258, 256979.0, 346557.0, 347794.22057794273, 349059.0, 350511.2474437637, 55056.898862023336, 55468.72265638646, 55535.44464555303, 55542.72228638831, 55605.72197138988, 56753.0, 57247.0, 57370.0, 57631.423685763446, 57681.0, 14670.413183472541, 15164.241787910782, 15268.465603703817, 15272.465463708939, 15273.847261527464, 9991.600335986624, 10709.518071686793, 10972.451377431096, 11555.88444115548, 11818.468168932419, 14898.553043408716, 14954.40182392692, 14970.251487425585, 14981.052463179716, 15118.773218401733, 5869.706514674249, 5963.850903727442, 5987.251593550806, 5990.101484777231, 6009.429104235091, 796.3589310604902, 798.7858455148142, 912.4434095201922, 992.0753737748744, 1036.6786296248188, 28382.71617283842, 28720.712792871804, 28768.280792980342, 29459.85270073679, 29805.552916706267, 988.649029594498, 990.8117457683161, 996.7757254617798, 1021.6271061062807, 1038.7610849504506, 13561.796573051412, 13957.88336933043, 14768.778468322984, 14808.629784255265, 14965.46773275239, 625353.0, 627839.0, 628474.0034098875, 629025.0, 630243.0, 45291.54708452939, 47543.81140471447, 53776.0, 53881.92236155255, 53923.46076539263, 1303.159462146929, 1316.605018494448, 1346.7373862096993, 1348.4606157537064, 1349.865013498662, 28561.714382855906, 28598.85700571484, 28642.0, 29236.853815730785, 30000.0, 37865.62134378621, 46038.539614603425, 46388.768056159504, 46403.53596463992, 46607.76696116634, 477567.0, 566009.0, 571490.0, 574856.0, 576199.0, 24548.877255614323, 25251.242462726146, 25349.366265843502, 26047.348816279744, 37198.69804556828, 42893.57106428896, 43171.0, 43662.0, 43946.12107757891, 44701.55298446974, 36610.718624848, 36739.0, 36826.0, 37899.052523687125, 38378.0, 36226.4566031515, 36416.0, 37498.8125059373, 37555.436668449445, 37753.81123094367, 34691.8275453652, 35471.467927980586, 35752.106197345274, 35894.64105358913, 36017.45973810447, 31459.0, 32667.34665306728, 32846.1788455281, 32976.0, 33078.0, 23702.762972370747, 24184.51630967406, 24403.877980609985, 24726.629100563146, 24741.381465463506], "value": 18953.443264199854, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.7017645530666, 377.5280898876405, 379.77782996946786, 381.8167279705741, 396.57368329046267, 16114.59713507163, 18282.634347313047, 19514.31699890504, 19735.703964440527, 20233.898830505863, 19300.324488642902, 19545.315913943014, 19739.0, 19783.307584234517, 19788.208471661117, 4637.559431853969, 5474.890502189954, 5641.850383082439, 5796.942030579682, 5803.883922321551, 5610.719464026803, 5794.797182098628, 5796.159556864252, 5800.245968024172, 5806.245188125559, 5481.095619222823, 5673.716314184274, 5732.312122545301, 5739.62692424991, 5756.769729210827, 5631.971840140794, 5686.232358631614, 5700.344460387026, 5707.486326230638, 5722.084466485342, 4738.905221895578, 4921.532454416824, 4990.750462476862, 5005.899882002376, 5014.598832093424, 24608.876955615287, 24615.507689846283, 24622.50754984908, 24673.506529869483, 24962.625560616605, 1958.4222654317011, 2539.3778524261675, 2698.055680511825, 2915.2566095645475, 2985.7606794903822, 8684.088170742107, 9981.550830212585, 9996.300258981873, 10005.699829005142, 10398.376097434179, 1811.4565630310985, 1846.5752876838403, 1899.3827006223032, 1920.0879582198484, 1966.5771859050374, 112752.99999999919, 117536.0, 121113.0, 121734.0, 122218.0, 9513.762155946157, 11154.776904461865, 11251.943740281245, 11577.089780186176, 11593.6521904343, 14277.571672849832, 14466.0, 14474.421023158955, 14488.710225795425, 14489.710205795825, 6896.551724138016, 7989.3209077229085, 8249.29880960125, 8280.461769984931, 8408.537530435762, 8115.0, 8129.4715843469985, 8192.467489613156, 8268.917310826815, 8284.792880178044, 4521.977390113028, 4526.909461810746, 4535.16099521583, 4548.795304211319, 4594.241950078266, 1754.7979633950529, 1902.1440351841727, 1973.8914359710066, 1977.7725561560605, 1977.9307724229584, 372.45993309701043, 374.8388193077003, 709.2234003765868, 752.8456666383378, 967.9943774579968, 11364.943175284236, 11659.533618655329, 11791.35147566892, 11972.64082077539, 11990.280583164864, 421.27330355137144, 436.92572262714776, 456.09010024999674, 827.6399766101765, 907.5388624314847, 10397.53211105487, 10703.357798532114, 10719.0, 10768.946155269174, 10821.621243256606, 577301.0, 580328.0, 586377.0, 595543.0, 596280.0, 123081.0, 128505.0, 137107.999999998, 138178.5455363624, 142240.0, 937.1144268666045, 960.3661583355092, 1002.3634991780272, 1002.3985608634906, 1005.1707341443192, 14188.716225675222, 17895.910520447836, 18214.81785182184, 18756.718649219998, 18762.155702993125, 35864.28271434609, 38608.42087368636, 40617.390739139526, 53396.0, 55833.0, 55858.0, 81286.59356703178, 81762.0, 81800.09398402167, 83757.0, 6639.535232533676, 7810.765677029698, 7988.281054705104, 8037.959810200911, 8259.917400825914, 12085.093617978511, 12282.078844086907, 12449.875501244871, 12456.688582785502, 12589.496420143092, 8546.572671366283, 8736.91263087361, 10483.895161048293, 10544.998225168596, 10961.397123158304, 10777.838332425177, 10987.780244395228, 11254.0, 11319.037881780141, 11331.32012079278, 11080.168987326144, 11460.885391146316, 11489.138314626269, 11527.942360288145, 11646.94176529112, 7999.240072193133, 8099.433039687401, 8158.551279679675, 8417.70538031166, 8424.725324162318, 10410.323328983592, 11149.498272577915, 11363.318200907972, 11419.543218271176, 11486.908743669339], "value": 9005.172064338652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.2757655712799, 1453.9054961427507, 1588.9602759931008, 1766.0728117738195, 1769.0889192066088, 7502.17476077631, 7711.845763084735, 7937.245961633643, 8338.666453341873, 8363.954505686788, 7843.411744119199, 8091.433599648027, 8126.593670316475, 8228.136045715204, 8249.0, 2243.6522339037433, 2286.7141607299086, 2288.7596802335765, 2293.54129174165, 2294.208498068164, 2269.387265438331, 2292.816574674022, 2294.070901284974, 2316.2124877541632, 2319.9072037118494, 1878.7369768232459, 2248.4378905273684, 2255.109231853417, 2275.9089636414524, 2282.8059614932668, 2212.9114835406644, 2225.2545397291765, 2226.1206823304706, 2243.809276211524, 2255.424866659008, 2257.796798288162, 2283.4976305212826, 2285.0517035430275, 2290.1640901071155, 2296.4144143243534, 8121.878171827428, 8243.917560824375, 8253.546054966975, 8265.710700125466, 8279.337652987746, 4211.7051806373565, 4760.952390476085, 4767.903382222108, 5165.819196328148, 5169.56058735008, 3186.49016157416, 3253.1704415374165, 3450.9482357764655, 3451.2752322012407, 3458.757886947915, 1031.4584842957433, 1058.1957712138753, 1065.6430095917876, 1082.226208261097, 1091.645215305021, 10670.679879603624, 10678.78642427155, 10733.51699173539, 10761.192910531741, 10809.189310801721, 4353.976815448367, 4545.954540454619, 4591.95408045922, 4655.604273636711, 4663.347131401607, 9165.0, 9318.254539636948, 9331.626734930662, 9332.206762425134, 9335.393199442151, 5019.7239151847, 5059.443461219336, 5075.086484432837, 5081.23781432788, 5086.669366491166, 4234.745915245096, 4499.932501012488, 4630.930536041962, 4652.488226295105, 4704.082703872712, 3755.455458958436, 3760.52993375828, 3867.400552914303, 3886.3393223152134, 3902.239063382613, 1223.3332833605707, 1240.9131360804656, 1244.2596654990266, 1249.3877999780225, 1252.8496580410408, 757.4319260554585, 779.8791187365968, 809.3929552835374, 895.3687650206589, 896.2381975320925, 5346.385165705916, 5443.5100840923715, 5569.498745112878, 5577.191307260425, 5721.0, 693.3309356470929, 737.6464188214579, 786.9921300787033, 806.6612022950375, 817.6034623207739, 3548.520949671761, 3645.2527231917393, 3739.8317075731657, 3808.7143464240294, 3812.6377994090517, 8986.326025548111, 9381.343305968654, 9717.9028209717, 9994.800103998024, 10186.796264074825, 9412.529373531295, 9633.0, 9940.900590994142, 9942.254330925209, 10118.342307749972, 7465.141508726458, 7674.462787604814, 7695.422843286776, 7701.653425595861, 7710.151883292833, 4869.561739443467, 5666.433356664301, 5671.404502527175, 5722.856928576819, 5856.912146317723, 9007.144321289466, 9145.588448519966, 9156.771080723036, 9160.358774885693, 9212.170904618617, 9133.132352426508, 9238.538073096184, 9247.630094796134, 9322.673706420243, 9431.104045115702, 7089.291070892869, 7215.494915355875, 7516.436267279866, 7711.498752581064, 7722.922770772445, 6816.693248803915, 7287.125544934642, 7919.326857217201, 7988.440809143537, 7992.760217193493, 6963.1296087989, 7006.334398232159, 7049.365557099885, 7071.858562828817, 7072.611006394698, 6616.8345791355605, 6801.659917004229, 6847.554908930903, 6872.725090996504, 6919.273476284918, 6406.743730250738, 6556.63938483388, 6640.203175618956, 6691.5315927884585, 6696.83257918556, 4294.227039132986, 4604.1252162089095, 4607.861764147081, 4629.0, 4646.790894409692, 4596.4714057883775, 4811.302361157683, 4823.252395878644, 4847.345608342828, 4972.950270497248], "value": 4317.502621617011, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.6000339546705, 306.38569667816034, 307.7399597340247, 312.17429897919993, 313.9983452785614, 5177.741112944356, 5267.86830329242, 5358.383785864627, 5387.5151236388765, 5490.337186464567, 4783.067301876135, 4995.277573722321, 5081.6696914700515, 5169.250458683488, 5377.5966802489775, 4122.42286079949, 4132.111596006858, 4150.771707556083, 4154.376843473488, 4169.041120542277, 3700.352438323296, 3916.373380259159, 4155.97922010391, 4189.937150942738, 4209.98960249541, 4054.513458384998, 4119.423280740699, 4139.81370838313, 4139.958600413987, 4158.64651504621, 3809.8857034289017, 3926.8219534139785, 4122.505299364066, 4141.585841415865, 4149.460570125894, 3638.072291565634, 3642.289753498069, 3652.1600031992525, 3667.8166091695307, 3692.5568931728367, 5221.817236396746, 5264.920691258283, 5268.841934741964, 5306.469353064701, 5349.090654588729, 4172.4784401949755, 4395.274779661352, 4739.241721324606, 4753.3107699383745, 4776.832810851603, 5372.54333381663, 5672.68800215988, 5708.94477987924, 5758.3665796762325, 5786.276715410574, 3770.3213421584105, 3978.4629075074786, 3981.9203615927518, 3988.840446382141, 4029.8388064477385, 4992.475790042066, 5965.761369545213, 5967.8209653710455, 5985.8802823943715, 6016.368281330485, 3277.8197199154274, 3468.5490886184634, 4054.655354294918, 4057.776822274803, 4086.734362266443, 3146.3167205544923, 3367.5958884933966, 3878.108035151931, 4114.382842573578, 4130.504339479282, 4725.00774837288, 4730.408698912636, 4747.216709243005, 4757.9065211306915, 4769.880752981134, 4978.850634480971, 5651.9152212716845, 5653.971730141323, 5702.889930725658, 5803.622764520293, 997.486294558303, 1245.701031752373, 1276.7829468990294, 1296.7989961555975, 1310.8361454818148, 787.5983248543284, 890.3011136258007, 924.4360939826701, 925.9305502145033, 930.4045410937006, 4608.769561521911, 5073.644844860898, 5085.618578606545, 5090.77091530882, 5100.948990510047, 4962.15643340633, 4999.375078115236, 5373.7850485980125, 5498.862528436741, 5576.553875690016, 4573.451185857718, 4682.648801339913, 4959.428756897054, 5036.521530454601, 5047.520485553867, 2657.8803953822126, 2759.696433392325, 2922.261254553537, 2940.412177424167, 3020.4412183746076, 5014.598832093424, 5789.45000224978, 5798.797042103506, 5802.390748971382, 5883.205767221456, 4677.573340131293, 5177.197534382177, 5737.88524229513, 6000.679510752251, 6031.517478601789, 5120.539151476385, 5995.970020149958, 6091.299500557493, 6101.877962440726, 6115.755369785248, 5615.747291371979, 5841.50347220491, 5895.997680394429, 5908.257064165995, 5912.320083190316, 5811.53507719373, 5840.649561026182, 5868.627872235212, 5872.265966754156, 5875.738964874536, 5794.420557944172, 6099.4510494053975, 6118.326984031664, 6151.6924153793025, 6158.397337557579, 3724.348239058208, 3772.8490860365278, 3872.0900588362274, 3914.784686842137, 3919.0790164310793, 5185.80726432915, 5303.867403314948, 5913.852153696192, 5929.318128415288, 5943.494802941798, 5124.538791508782, 5272.86817829542, 5662.490375866192, 5775.624584402, 5869.148973398918, 4528.3660287559105, 4921.941782516776, 5803.7388317524965, 5812.0, 5828.795992140254, 5120.283160357628, 5365.785368585369, 5637.46444087811, 5693.231413759255, 5700.515456186271, 5747.339056008613, 5766.163906233656, 5822.184894114743, 5835.387284335254, 5835.416458354131, 4997.575206107376, 5661.830145095653, 5678.403767604342, 5680.4319568042865, 5696.259486266758], "value": 4147.018196795338, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.9095622091016, 686.2005763285772, 706.3995603736824, 742.3764038207904, 792.6643605524689, 13958.371873265702, 14816.259187040632, 14908.478203262888, 14935.0, 14975.999999999987, 13353.866461335383, 13810.516631917884, 13947.651308717288, 14098.365573549188, 14100.717985640282, 817.0930267403178, 830.2859540794911, 830.5307501261768, 837.1377481194379, 839.4879123734516, 647.4917190005847, 667.5594107888794, 808.915063918289, 822.4284122534841, 825.5170725125809, 861.9741407757778, 879.3361012435602, 880.6961598248594, 881.9470831750116, 883.196291374849, 828.7223780033696, 857.9442336248123, 862.3489265604492, 865.4201684871151, 871.2681347667989, 548.5995223486877, 631.8072987738738, 641.9775307864203, 643.4144928115393, 644.5520363347475, 12349.444275007647, 12692.30192339421, 14567.562973110824, 15180.0, 15261.313240904186, 14058.734713875698, 14162.0, 14211.21838298893, 14320.856791432056, 14362.281885905768, 10107.63546921171, 11801.993661077551, 11901.226420282654, 12023.977961873252, 12336.938315308456, 3618.6924111450558, 3814.7138964577766, 3820.8853734388013, 3844.0774214188673, 3859.36320507116, 119043.4047829764, 123804.38097809543, 125926.0, 126264.0, 126290.0, 8798.29613630908, 9862.802743945082, 10836.512356943957, 10984.835227471594, 11083.057940075183, 22147.446313842283, 22229.333120006424, 22263.554728905332, 22269.443263918532, 22320.21879234219, 7532.17146113927, 7625.084989801259, 7649.31156195945, 7771.0, 7855.5286682799215, 4154.086101057763, 4247.277962746341, 4252.85115020979, 4262.147570485915, 4275.8075886585175, 3397.8810741623925, 3478.89563313101, 3505.4216054350745, 3506.7369947253546, 3513.262214934892, 551.0796969061677, 598.9219405070855, 608.4219991008471, 618.2611778924143, 621.7109044294425, 584.6024703201779, 605.833395816154, 626.7493002798917, 651.5504302031583, 670.0786418674322, 8700.608472618747, 8855.73432797017, 8877.467351958903, 9152.947411047682, 9157.542122893828, 622.2129006806393, 643.5109316919176, 648.9286178520359, 653.7319698923513, 667.7362441835447, 4282.250606143913, 4314.80583373749, 4441.601535170971, 4545.001097344434, 4621.23749581322, 108463.45768271109, 112036.0, 113034.43482782533, 115431.0, 115921.42039289749, 83930.5803470991, 90481.54759226162, 91647.54176229076, 100313.0, 106641.0, 82280.58859705663, 85184.57407712922, 85335.0, 85620.57189714136, 85823.0, 7618.733344332922, 8008.879866802119, 8520.659173632985, 8655.48067115975, 8750.212480876751, 43183.568164317956, 53177.7341113292, 55599.61000975008, 56589.47284217335, 56693.71653141708, 72405.27594724196, 77205.61397193203, 77868.22131778837, 79301.81047284411, 79412.80880786908, 11027.72430689207, 11198.664040078811, 12627.684307892008, 12724.936375318064, 13473.865261347653, 12084.982150570693, 12480.687982800502, 13899.513517026855, 13915.652108697364, 14644.633884152981, 12955.352232388155, 12981.221126732426, 13050.477980881038, 13252.271125088213, 13533.526326578523, 13088.0, 13190.188608203045, 13204.27376494302, 13465.528706495226, 13686.863131368558, 9884.604615815575, 12396.876031239934, 12963.546275880299, 13021.0, 13387.196768193939, 10609.160869174693, 11022.779544408906, 11281.379524125923, 11295.491702873225, 11408.372539510076, 8872.689455869013, 9779.728635277368, 11009.504572294427, 11096.420206101766, 11278.718032049264], "value": 7347.063480663654, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 123.0, 120.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 117.0, 151.0, 116.0, 120.0, 118.0, 121.0, 139.0, 103.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 205.0, 208.0, 203.0, 197.0, 217.0, 199.0, 271.0, 247.0, 196.0], "value": 213.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 66.0, 84.0, 99.0, 62.0, 60.0, 65.0, 113.0, 100.0, 63.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 83.0, 72.0, 71.0, 71.0, 84.0, 77.0, 85.0, 84.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 84.0, 89.0, 86.0, 90.0, 85.0, 91.0, 86.0, 89.0, 106.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 99.0, 113.0, 99.0, 99.0, 125.0, 175.0, 102.0, 131.0], "value": 114.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 92.0, 92.0, 96.0, 98.0, 144.0, 126.0, 96.0, 120.0], "value": 105.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 59.0, 58.0, 59.0, 61.0, 56.0, 58.0, 58.0, 70.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 112.0, 107.0, 83.0, 79.0, 73.0, 89.0, 61.0, 72.0, 68.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 87.0, 94.0, 105.0, 78.0, 78.0, 100.0, 113.0, 108.0, 82.0], "value": 94.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 158.0, 150.0, 164.0, 166.0, 150.0, 155.0, 158.0, 265.0, 187.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 178.0, 178.0, 179.0, 192.0, 175.0, 177.0, 175.0, 161.0, 168.0], "value": 176.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 71.0, 67.0, 70.0, 71.0, 72.0, 73.0, 71.0, 71.0], "value": 70.4, "unit": "score"}]}]} 15:37:57 INFO - Return code: 0 15:37:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:37:58 ERROR - # TBPL SUCCESS # 15:37:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:37:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:37:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:37:58 INFO - 2016-12-03 15:37:58.029 screenresolution[1682:11232] starting screenresolution argv=screenresolution get 15:37:58 INFO - 2016-12-03 15:37:58.032 screenresolution[1682:11232] Display 0: 1600x1200x32@60 15:37:58 INFO - 2016-12-03 15:37:58.038 screenresolution[1683:11235] starting screenresolution argv=screenresolution list 15:37:58 INFO - Available Modes on Display 0 15:37:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:37:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:37:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:37:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:37:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:37:58 INFO - Intel Iris: 15:37:58 INFO - Chipset Model: Intel Iris 15:37:58 INFO - Type: GPU 15:37:58 INFO - Bus: Built-In 15:37:58 INFO - VRAM (Dynamic, Max): 1536 MB 15:37:58 INFO - Vendor: Intel (0x8086) 15:37:58 INFO - Device ID: 0x0a2e 15:37:58 INFO - Revision ID: 0x0009 15:37:58 INFO - Displays: 15:37:58 INFO - Display: 15:37:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:37:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:37:58 INFO - Display Serial Number: 1600x1200 60 15:37:58 INFO - Main Display: Yes 15:37:58 INFO - Mirror: Off 15:37:58 INFO - Online: Yes 15:37:58 INFO - Rotation: Supported 15:37:58 INFO - Return code: 0 15:37:58 INFO - Running post-action listener: _resource_record_post_action 15:37:58 INFO - [mozharness: 2016-12-03 23:37:58.281099Z] Finished run-tests step (success) 15:37:58 INFO - Running post-run listener: _resource_record_post_run 15:37:58 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32260608; Write bytes: 693084672; Read time: 318; Write time: 1960 15:37:58 INFO - TinderboxPrint: CPU usage
54.4% 15:37:58 INFO - TinderboxPrint: I/O read bytes / time
32,260,608 / 318 15:37:58 INFO - TinderboxPrint: I/O write bytes / time
693,084,672 / 1,960 15:37:58 INFO - TinderboxPrint: CPU idle
2,213.7 (45.6%) 15:37:58 INFO - TinderboxPrint: CPU system
974.2 (20.1%) 15:37:58 INFO - TinderboxPrint: CPU user
1,661.7 (34.3%) 15:37:58 INFO - TinderboxPrint: Swap in / out
412,753,920 / 0 15:37:58 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 367483392; Write bytes: 368392192; Read time: 27606; Write time: 651 15:37:58 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31330816; Write bytes: 306211328; Read time: 305; Write time: 1262 15:37:58 INFO - Running post-run listener: _upload_blobber_files 15:37:58 INFO - Blob upload gear active. 15:37:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:37:58 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 15:37:58 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'] 15:37:58 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 15:37:59 INFO - (blobuploader) - INFO - Open directory for files ... 15:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:38:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:38:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:38:02 INFO - (blobuploader) - INFO - Done attempting. 15:38:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:38:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:38:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:38:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:38:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:38:04 INFO - (blobuploader) - INFO - Done attempting. 15:38:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:38:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:38:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:38:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:38:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:38:06 INFO - (blobuploader) - INFO - Done attempting. 15:38:06 INFO - (blobuploader) - INFO - Iteration through files over. 15:38:06 INFO - Return code: 0 15:38:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:38:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:38:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b74410485d52147a79556f87abc8e8985d291d9d1bafa3185594c1c2462b418e839a00768a46d129c388e25d4258085a5eb320d86ff8f078f89238c61f0b883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae85b9a00cfddd240054abc4b3896408cd61ddc64b651fde3f25e6f596e41cc501ddcedf20329f47c1117b819867014580b64b48d0375e428b888058c9be684", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609c65920d660ed798b6433778667909fc8e020e6985162ed8aa645e5b82e74b016cdfe3663ea6ac8f23f8e7f3bf3772f946cfb6e97d8f4e6f92a6d235386806"} 15:38:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:38:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:38:06 INFO - Contents: 15:38:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b74410485d52147a79556f87abc8e8985d291d9d1bafa3185594c1c2462b418e839a00768a46d129c388e25d4258085a5eb320d86ff8f078f89238c61f0b883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae85b9a00cfddd240054abc4b3896408cd61ddc64b651fde3f25e6f596e41cc501ddcedf20329f47c1117b819867014580b64b48d0375e428b888058c9be684", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609c65920d660ed798b6433778667909fc8e020e6985162ed8aa645e5b82e74b016cdfe3663ea6ac8f23f8e7f3bf3772f946cfb6e97d8f4e6f92a6d235386806"} 15:38:06 INFO - Running post-run listener: copy_logs_to_upload_dir 15:38:06 INFO - Copying logs to upload dir... 15:38:06 INFO - mkdir: /builds/slave/test/build/upload/logs 15:38:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.488743 ========= master_lag: 0.79 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-03 15:38:07.322446) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 15:38:07.325691) ========= 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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/6b74410485d52147a79556f87abc8e8985d291d9d1bafa3185594c1c2462b418e839a00768a46d129c388e25d4258085a5eb320d86ff8f078f89238c61f0b883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae85b9a00cfddd240054abc4b3896408cd61ddc64b651fde3f25e6f596e41cc501ddcedf20329f47c1117b819867014580b64b48d0375e428b888058c9be684", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609c65920d660ed798b6433778667909fc8e020e6985162ed8aa645e5b82e74b016cdfe3663ea6ac8f23f8e7f3bf3772f946cfb6e97d8f4e6f92a6d235386806"} build_url:https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/HDqVzYzkT9qaZAloxs6rlA/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/6b74410485d52147a79556f87abc8e8985d291d9d1bafa3185594c1c2462b418e839a00768a46d129c388e25d4258085a5eb320d86ff8f078f89238c61f0b883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ae85b9a00cfddd240054abc4b3896408cd61ddc64b651fde3f25e6f596e41cc501ddcedf20329f47c1117b819867014580b64b48d0375e428b888058c9be684", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609c65920d660ed798b6433778667909fc8e020e6985162ed8aa645e5b82e74b016cdfe3663ea6ac8f23f8e7f3bf3772f946cfb6e97d8f4e6f92a6d235386806"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 15:38:07.360500) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 15:38:07.360978) ========= 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.hwyg9S44Ky/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.qkyQcsHXzd/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005172 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 15:38:07.409055) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-03 15:38:07.409389) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-03 15:38:07.409695) ========= ========= Total master_lag: 0.92 =========